2025-12-09T03:46:01.772649Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:544: RowDispatcher: Successfully bootstrapped row dispatcher, id [1:7581699445347219959:2069], tenant /default
2025-12-09T03:46:01.772676Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581699445347219959:2069]
2025-12-09T03:46:01.772679Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:329: Coordinator: Add new row dispatcher to map (state 1)
2025-12-09T03:46:01.773171Z :GRPC_SERVER NOTICE: grpc_request_proxy_simple.cpp:161: Grpc simple request proxy started, nodeid# 1
2025-12-09T03:46:01.773229Z :GRPC_SERVER NOTICE: grpc_request_proxy_simple.cpp:161: Grpc simple request proxy started, nodeid# 1
2025-12-09T03:46:01.773432Z :FQ_CONTROL_PLANE_CONFIG DEBUG: control_plane_config.cpp:58: STARTING: [1:7581699445347219953:2063]
2025-12-09T03:46:01.773626Z :YQL_NODES_MANAGER INFO: nodes_manager.cpp:88: Bootstrap, InstanceId: d0342403-4b9dc8b8-255fcaa8-59ab68a
2025-12-09T03:46:01.773629Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:310: TNodesManagerActor::ResolveSelfAddress
2025-12-09T03:46:01.773643Z :YQ_TEST_CONNECTION DEBUG: test_connection.cpp:142: Starting yandex query test connection. Actor id: [1:7581699445347219964:2074]
2025-12-09T03:46:01.773699Z :FQ_LOG_UPDATER DEBUG: log.cpp:93: Updated YQL logs priority: 8
2025-12-09T03:46:01.773705Z :FQ_LOG_UPDATER CRIT: log.cpp:55: Failed to deliver subscription request to config dispatcher.
2025-12-09T03:46:01.773897Z :FQ_QUOTA_SERVICE INFO: quota_manager.cpp:196: STARTED
2025-12-09T03:46:01.773904Z :YQ_CONTROL_PLANE_STORAGE INFO: ydb_control_plane_storage.cpp:50: Starting ydb control plane storage service. Actor id: [1:7581699445347219952:2062]
2025-12-09T03:46:01.774259Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/bindings" actor
2025-12-09T03:46:01.774301Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:293: Coordinator: Successfully bootstrapped coordinator, id [1:7581699445347219975:2081], NodesManagerId [0:4705493345362661702:78], rebalancing timeout 5.000000s
2025-12-09T03:46:01.774316Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:232: TLeaderElection [1:7581699445347219976:2082] Successfully bootstrapped, local coordinator id [1:7581699445347219975:2081], tenant id default, local mode 0, coordination node path local/default, endpoint localhost:31865
2025-12-09T03:46:01.774326Z :YQ_CONTROL_PLANE_PROXY DEBUG: control_plane_proxy.cpp:525: Starting yandex query control plane proxy. Actor id: [1:7581699445347219954:2064]
2025-12-09T03:46:01.774711Z :YQ_RATE_LIMITER INFO: quoter_service.cpp:124: Bootstraping with config: { Enabled: true ControlPlaneEnabled: true DataPlaneEnabled: true Database { Endpoint: "localhost:31865" Database: "local" TablePrefix: "RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839" } Limiters { CoordinationNodePath: "rate_limiter" } }
2025-12-09T03:46:01.774843Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run create coordination node "local/RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839/rate_limiter" actor
2025-12-09T03:46:01.774852Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create coordination node "local/RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839/rate_limiter"
2025-12-09T03:46:01.774854Z :YQ_RATE_LIMITER DEBUG: schema.cpp:411: Call create coordination node "local/RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839/rate_limiter"
2025-12-09T03:46:01.774871Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create directory "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839" actor
2025-12-09T03:46:01.774872Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create directory "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839"
2025-12-09T03:46:01.774873Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:383: Call create directory "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839"
2025-12-09T03:46:01.774889Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/queries" actor
2025-12-09T03:46:01.774917Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/idempotency_keys" actor
2025-12-09T03:46:01.774926Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/result_sets" actor
2025-12-09T03:46:01.774929Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/pending_small" actor
2025-12-09T03:46:01.774936Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/jobs" actor
2025-12-09T03:46:01.774946Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:214: Bootstrap
2025-12-09T03:46:01.774948Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:217: Create sdk ydb connection
2025-12-09T03:46:01.774972Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b43101, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:01.774976Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:239: Successfully bootstrapped TStorageProxy [1:7581699445347219958:2068] with connection to localhost:31865:local
2025-12-09T03:46:01.774981Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/mappings" actor
2025-12-09T03:46:01.774982Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:637: RowDispatcher: TEvCoordinatorChangesSubscribe from [1:7581699445347219975:2081]
2025-12-09T03:46:01.774989Z :FQ_QUOTA_PROXY INFO: quota_proxy.cpp:90: STARTED
2025-12-09T03:46:01.774992Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/connections" actor
2025-12-09T03:46:01.775003Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/nodes" actor
2025-12-09T03:46:01.775010Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/quotas" actor
2025-12-09T03:46:01.775026Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenants" actor
2025-12-09T03:46:01.775032Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenant_acks" actor
2025-12-09T03:46:01.775042Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/compute_databases" actor
2025-12-09T03:46:01.775162Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-09T03:46:01.776120Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:01.776133Z :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:27714: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint localhost:27714
2025-12-09T03:46:01.776178Z :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:27714: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint localhost:27714
2025-12-09T03:46:01.776711Z :MEMORY_PROFILER NOTICE: monitor.cpp:332: Bootstrapped
2025-12-09T03:46:01.802486Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:71: Run create coordination node "local/default" actor
2025-12-09T03:46:01.802496Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:113: Call create coordination node "local/default"
2025-12-09T03:46:01.802498Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:411: Call create coordination node "local/default"
2025-12-09T03:46:01.834855Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:155: Successfully created coordination node "local/default"
2025-12-09T03:46:01.834872Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:122: Reply for create coordination node "local/default": { <main>: Error: Check failed: path: '/local/default', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 7], type: EPathTypeKesus, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_kesus.cpp:339 }
2025-12-09T03:46:01.834886Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:345: TLeaderElection [1:7581699445347219976:2082] Coordination node successfully created
2025-12-09T03:46:01.834888Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:323: TLeaderElection [1:7581699445347219976:2082] Start session
2025-12-09T03:46:01.836526Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:359: TLeaderElection [1:7581699445347219976:2082] Session successfully created
2025-12-09T03:46:01.838683Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:372: TLeaderElection [1:7581699445347219976:2082] Semaphore successfully created
2025-12-09T03:46:01.838695Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:304: TLeaderElection [1:7581699445347219976:2082] Try to acquire semaphore
2025-12-09T03:46:01.838807Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:420: TLeaderElection [1:7581699445347219976:2082] Describe semaphore
2025-12-09T03:46:01.839947Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/compute_databases". Create session OK
2025-12-09T03:46:01.839951Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/compute_databases"
2025-12-09T03:46:01.839952Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/compute_databases"
2025-12-09T03:46:01.839995Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/nodes". Create session OK
2025-12-09T03:46:01.839999Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/nodes"
2025-12-09T03:46:01.840001Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/nodes"
2025-12-09T03:46:01.840006Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/queries". Create session OK
2025-12-09T03:46:01.840010Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/queries"
2025-12-09T03:46:01.840013Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/queries"
2025-12-09T03:46:01.840162Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/idempotency_keys". Create session OK
2025-12-09T03:46:01.840163Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/idempotency_keys"
2025-12-09T03:46:01.840165Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/idempotency_keys"
2025-12-09T03:46:01.840205Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/connections". Create session OK
2025-12-09T03:46:01.840207Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/connections"
2025-12-09T03:46:01.840207Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/jobs". Create session OK
2025-12-09T03:46:01.840208Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/connections"
2025-12-09T03:46:01.840209Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/jobs"
2025-12-09T03:46:01.840211Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/jobs"
2025-12-09T03:46:01.840280Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created directory "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839"
2025-12-09T03:46:01.840286Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create directory "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 46], type: EPathTypeDir, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_mkdir.cpp:155 }
2025-12-09T03:46:01.840291Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenants". Create session OK
2025-12-09T03:46:01.840293Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenants"
2025-12-09T03:46:01.840294Z :YQ_RATE_LIMITER DEBUG: schema.cpp:155: Successfully created coordination node "local/RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839/rate_limiter"
2025-12-09T03:46:01.840294Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenants"
2025-12-09T03:46:01.840298Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for create coordination node "local/RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839/rate_limiter": { <main>: Error: Check failed: path: '/local/RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839/rate_limiter', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 50], type: EPathTypeKesus, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_kesus.cpp:339 }
2025-12-09T03:46:01.840301Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/mappings". Create session OK
2025-12-09T03:46:01.840302Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/mappings"
2025-12-09T03:46:01.840312Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/mappings"
2025-12-09T03:46:01.840338Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenant_acks". Create session OK
2025-12-09T03:46:01.840340Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenant_acks"
2025-12-09T03:46:01.840342Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenant_acks"
2025-12-09T03:46:01.840364Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/result_sets". Create session OK
2025-12-09T03:46:01.840366Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/result_sets"
2025-12-09T03:46:01.840367Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/result_sets"
2025-12-09T03:46:01.840403Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/bindings". Create session OK
2025-12-09T03:46:01.840405Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/bindings"
2025-12-09T03:46:01.840405Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/bindings"
2025-12-09T03:46:01.840415Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/pending_small". Create session OK
2025-12-09T03:46:01.840416Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/pending_small"
2025-12-09T03:46:01.840417Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/pending_small"
2025-12-09T03:46:01.840436Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/quotas". Create session OK
2025-12-09T03:46:01.840438Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/quotas"
2025-12-09T03:46:01.840439Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/quotas"
2025-12-09T03:46:01.840496Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:386: TLeaderElection [1:7581699445347219976:2082] Semaphore successfully acquired
2025-12-09T03:46:01.840587Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:469: TLeaderElection [1:7581699445347219976:2082] Semaphore successfully described: coordinator id [1:7581699445347219975:2081] generation 5
2025-12-09T03:46:01.840596Z :FQ_ROW_DISPATCHER INFO: leader_election.cpp:471: TLeaderElection [1:7581699445347219976:2082] Send TEvCoordinatorChanged to [1:7581699445347219959:2069], new coordinator id [1:7581699445347219975:2081], previous coordinator id [0:0:0]
2025-12-09T03:46:01.840611Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:569: RowDispatcher: Coordinator changed, old leader (empty maybe), new [1:7581699445347219975:2081] generation 5
2025-12-09T03:46:01.840622Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7581699445347219959:2069]
2025-12-09T03:46:01.840625Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581699445347219959:2069]
2025-12-09T03:46:01.840627Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7581699445347219959:2069]
2025-12-09T03:46:01.840636Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:440: Coordinator: New leader [1:7581699445347219975:2081], SelfId [1:7581699445347219975:2081]
2025-12-09T03:46:01.840638Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-09T03:46:01.843366Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/bindings"
2025-12-09T03:46:01.843377Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/bindings": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/bindings', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 54], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:01.843477Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/compute_databases"
2025-12-09T03:46:01.843493Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/compute_databases": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/compute_databases', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 57], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:01.844138Z :FQ_CONTROL_PLANE_CONFIG DEBUG: control_plane_config.cpp:190: LOADED TenantInfo: State CHANGED at 2025-12-09T03:45:40.495416Z
2025-12-09T03:46:01.845092Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/idempotency_keys"
2025-12-09T03:46:01.845103Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/idempotency_keys": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/idempotency_keys', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 52], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:01.845268Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/queries"
2025-12-09T03:46:01.845276Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/queries": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/queries', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 61], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:01.845358Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenants"
2025-12-09T03:46:01.845365Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenants": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenants', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 47], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:01.845417Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/mappings"
2025-12-09T03:46:01.865020Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/mappings": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/mappings', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 48], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:01.865208Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/quotas"
2025-12-09T03:46:01.865223Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/quotas": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/quotas', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 51], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:01.865284Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/jobs"
2025-12-09T03:46:01.865294Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/jobs": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/jobs', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 60], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:01.865332Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenant_acks"
2025-12-09T03:46:01.865340Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenant_acks": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/tenant_acks', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 53], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:01.865343Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/connections"
2025-12-09T03:46:01.865347Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/connections": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/connections', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 56], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:01.865394Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/nodes"
2025-12-09T03:46:01.865404Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/nodes": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/nodes', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 55], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:01.865633Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/result_sets"
2025-12-09T03:46:01.865653Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/result_sets": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/result_sets', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 59], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:01.865684Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/pending_small"
2025-12-09T03:46:01.865694Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/pending_small": { <main>: Error: Check failed: path: '/local/DbPoolStorage_9e3d4ab0-4c3c-4175-a077-153e07479839/pending_small', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 58], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:46:02.749920Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b43102, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:02.962279Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43102, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:46:02.964886Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3dc69888-647dc2a0-292de72d-727b43102"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:46:02.972622Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:46:02.972663Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43102, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:46:02.973243Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:02.973251Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:46:03.750338Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:627: RowDispatcher: Send ping to [1:7581699445347219975:2081]
2025-12-09T03:46:03.750347Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b43103, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:03.750348Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1174: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-09T03:46:03.750360Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7581699445347219959:2069]
2025-12-09T03:46:03.750363Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581699445347219959:2069]
2025-12-09T03:46:03.762145Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43103, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:46:03.762176Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3dc69888-647dc2a0-292de72d-727b43103"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:46:03.887183Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7581699445347219959:2069]
2025-12-09T03:46:03.887214Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-09T03:46:03.887704Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:46:03.887742Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43103, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:46:03.889673Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:03.889687Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:46:04.750895Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b43104, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:04.751729Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43104, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:46:04.751823Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3dc69888-647dc2a0-292de72d-727b43104"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:46:04.758348Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:46:04.758374Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43104, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:46:04.760235Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:04.760246Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:46:04.813286Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-09T03:46:05.752402Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:627: RowDispatcher: Send ping to [1:7581699445347219975:2081]
2025-12-09T03:46:05.752410Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1174: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-09T03:46:05.752438Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b43105, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:05.752447Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7581699445347219959:2069]
2025-12-09T03:46:05.752449Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581699445347219959:2069]
2025-12-09T03:46:05.752452Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7581699445347219959:2069]
2025-12-09T03:46:05.752663Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-09T03:46:05.752933Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43105, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:46:05.752981Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3dc69888-647dc2a0-292de72d-727b43105"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:46:05.755799Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:46:05.755822Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43105, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:46:05.756029Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:05.756037Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:46:06.749647Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-09T03:46:06.749908Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:665: Coordinator: Change global state to Started (by timeout)
2025-12-09T03:46:06.750464Z :YQ_CONTROL_PLANE_STORAGE TRACE: nodes_health_check.cpp:38: NodesHealthCheckRequest: {tenant: "/default"
node {
  node_id: 1
  instance_id: "d0342403-4b9dc8b8-255fcaa8-59ab68a"
  hostname: "ghrun-syjwtgwtdu"
  interconnect_port: 15489
  node_address: "127.0.1.1"
}
}
2025-12-09T03:46:06.753355Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b43106, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:06.753841Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43106, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:46:06.753871Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3dc69888-647dc2a0-292de72d-727b43106"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:46:06.765628Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:46:06.765675Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43106, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:46:06.766043Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:06.766053Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:46:06.779374Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: NodesHealthCheckRequest - NodesHealthCheckResult: {nodes { node_id: 1 instance_id: "d0342403-4b9dc8b8-255fcaa8-59ab68a" hostname: "ghrun-syjwtgwtdu" node_address: "127.0.1.1" } } SUCCESS
2025-12-09T03:46:06.779799Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-12-09T03:46:07.753159Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:627: RowDispatcher: Send ping to [1:7581699445347219975:2081]
2025-12-09T03:46:07.753171Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1174: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-09T03:46:07.753200Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b43107, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:07.753211Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7581699445347219959:2069]
2025-12-09T03:46:07.753214Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581699445347219959:2069]
2025-12-09T03:46:07.753220Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7581699445347219959:2069]
2025-12-09T03:46:07.753425Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-09T03:46:07.753673Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43107, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:46:07.753711Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3dc69888-647dc2a0-292de72d-727b43107"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:46:07.758455Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:46:07.758488Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43107, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:46:07.758818Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:07.758828Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:46:07.812995Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-09T03:46:08.753609Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b43108, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:08.754064Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43108, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:46:08.754104Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3dc69888-647dc2a0-292de72d-727b43108"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:46:08.760492Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:46:08.760531Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43108, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:46:08.760833Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:08.760840Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:46:09.754488Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:627: RowDispatcher: Send ping to [1:7581699445347219975:2081]
2025-12-09T03:46:09.754504Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1174: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-09T03:46:09.755128Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b43109, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:09.755146Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7581699445347219959:2069]
2025-12-09T03:46:09.755150Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581699445347219959:2069]
2025-12-09T03:46:09.755152Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7581699445347219959:2069]
2025-12-09T03:46:09.755387Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-09T03:46:09.758251Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43109, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:46:09.758303Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3dc69888-647dc2a0-292de72d-727b43109"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:46:09.766419Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:46:09.766465Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b43109, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:46:09.766876Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:09.766880Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:46:10.755547Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b431010, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:10.756087Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b431010, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 69 bytes
2025-12-09T03:46:10.756130Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3dc69888-647dc2a0-292de72d-727b431010"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:46:10.758861Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:46:10.758920Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b431010, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:46:10.759216Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:10.759224Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:46:10.819771Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-09T03:46:11.750010Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:599: Coordinator: Send TEvGetNodesRequest to NodesManager
2025-12-09T03:46:11.750024Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-09T03:46:11.750220Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:300: Received TNodesManagerActor::TEvGetNodesRequest
2025-12-09T03:46:11.750249Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:690: Coordinator: Updated node info, node count: 1
2025-12-09T03:46:11.750701Z :YQ_CONTROL_PLANE_STORAGE TRACE: nodes_health_check.cpp:38: NodesHealthCheckRequest: {tenant: "/default"
node {
  node_id: 1
  instance_id: "d0342403-4b9dc8b8-255fcaa8-59ab68a"
  hostname: "ghrun-syjwtgwtdu"
  interconnect_port: 15489
  node_address: "127.0.1.1"
}
}
2025-12-09T03:46:11.754948Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:627: RowDispatcher: Send ping to [1:7581699445347219975:2081]
2025-12-09T03:46:11.754970Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1174: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-09T03:46:11.754977Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7581699445347219959:2069]
2025-12-09T03:46:11.754980Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581699445347219959:2069]
2025-12-09T03:46:11.754982Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7581699445347219959:2069]
2025-12-09T03:46:11.754990Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-09T03:46:11.756311Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b431011, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:11.756822Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b431011, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 69 bytes
2025-12-09T03:46:11.756875Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3dc69888-647dc2a0-292de72d-727b431011"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:46:11.763951Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:46:11.763991Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b431011, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:46:11.764285Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:11.764293Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:46:11.766118Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: NodesHealthCheckRequest - NodesHealthCheckResult: {nodes { node_id: 1 instance_id: "d0342403-4b9dc8b8-255fcaa8-59ab68a" hostname: "ghrun-syjwtgwtdu" node_address: "127.0.1.1" } } SUCCESS
2025-12-09T03:46:11.766437Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-12-09T03:46:12.758420Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b431012, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:12.762155Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b431012, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 69 bytes
2025-12-09T03:46:12.762186Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3dc69888-647dc2a0-292de72d-727b431012"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:46:12.766099Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:456: Task (Query): ptqudqgd0e9tq55enbt0 Lease TIMEOUT, RetryCounterUpdatedAt 2025-12-09T03:45:50.406241Z LastSeenAt: 2025-12-09T03:45:51.996792Z
2025-12-09T03:46:12.766111Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:463: Task (Query): ptqudqgd0e9tq55enbt0 RetryRate: 0.9996318169 RetryCounter: 1 At: 2025-12-09T03:46:12.766060Z
2025-12-09T03:46:12.807694Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {tasks { query_id { value: "ptqudqgd0e9tq55enbt0" } job_id { value: "ptjudqgd08t5i4oh6p8n" } generation: 5 streaming: true dq_graph: "\022\230\006\010\001\022\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\000\000\000\000\032\004\010\240\215\006*\345\002Z\342\002\n\010PqSource\022\323\002\n4type.googleapis.com/NYql.NPq.NProto.TDqPqTopicSource\022\232\002\n\020continue_1_input\022\024ptqudqgd0e9tq55enbt0\032\017localhost:31865\"\025\n\023cluster:default_yds*\005local0\002@\001J\016FakeDatabaseIdb\004Dataj\025[\"DataType\";\"String\"]\222\001\024ptcudqgd0emir08c6gg2\232\001\003raw\242\001/[\"StructType\";[[\"Data\";[\"DataType\";\"String\"]]]]\262\001\004*\002\010\001\272\001\026\n\010cloud_id\022\nmock_cloud\272\001 \n\005scope\022\027yandexcloud://my_folder\030\0012\211\001z\206\001\n\006PqSink\022|\n2type.googleapis.com/NYql.NPq.NProto.TDqPqTopicSink\022F\n\021continue_2_output\022\017localhost:31865\032\025\n\023cluster:default_yds\"\005local0\0018\002:\203\001\n*type.googleapis.com/Yql.DqsProto.TTaskMeta\022U\010\246\240\014\022A\022(2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707\032\023dq_vanilla_job.lite \002\"\014\n\002pq\022\006\n\004\010\002\030\001H\246\240\014RClocal/RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839/rate_limiterZ)mock_cloud/my_folder/ptqudqgd0e9tq55enbt0`\200\200\200\200 \230\001\000\300\001\001\022\230\006\010\002\022\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\000\000\000\000\032\004\010\240\215\006*\345\002Z\342\002\n\010PqSource\022\323\002\n4type.googleapis.com/NYql.NPq.NProto.TDqPqTopicSource\022\232\002\n\020continue_2_input\022\024ptqudqgd0e9tq55enbt0\032\017localhost:31865\"\025\n\023cluster:default_yds*\005local0\002@\001J\016FakeDatabaseIdb\004Dataj\025[\"DataType\";\"String\"]\222\001\024ptcudqgd0emir08c6gg2\232\001\003raw\242\001/[\"StructType\";[[\"Data\";[\"DataType\";\"String\"]]]]\262\001\004*\002\010\001\272\001\026\n\010cloud_id\022\nmock_cloud\272\001 \n\005scope\022\027yandexcloud://my_folder\030\0012\211\001z\206\001\n\006PqSink\022|\n2type.googleapis.com/NYql.NPq.NProto.TDqPqTopicSink\022F\n\021continue_2_output\022\017localhost:31865\032\025\n\023cluster:default_yds\"\005local0\0018\002:\203\001\n*type.googleapis.com/Yql.DqsProto.TTaskMeta\022U\010\271\240\014\022A\022(2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707\032\023dq_vanilla_job.lite \002\"\014\n\002pq\022\006\n\004\010\002\030\001H\271\240\014RClocal/RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839/rate_limiterZ)mock_cloud/my_folder/ptqudqgd0e9tq55enbt0`\200\200\200\200 \230\001\000\300\001\0012rptqudqgd0e9tq55enbt0#ptrudqgd08dvddiflcsg#yandexcloud://my_folder#77ade900-f2dd9c75-c025c248-bda0739c12#mock_cloud:\027\n\016FallbackPolicy\022\005never:\024\n\017_LiteralTimeout\022\0010:\022\n\r_TableTimeout\022\0010:\027\n\022_RowsLimitPerWrite\022\0010:\027\n\022EnableComputeActor\022\0011:\016\n\014WorkerFilter:\031\n\020ComputeActorType\022\005async:\031\n\016WatermarksMode\022\007disable:\037\n\027WatermarksGranularityMs\022\0041000:$\n\034WatermarksLateArrivalDelayMs\022\0045000:\027\n\022EnableChannelStats\022\0011:\020\n\013ExportStats\022\0011B%\n\023cluster:default_yds\022\016{\"no_auth\":\"\"}b\202\002\010\271\240\014\022\373\001\037\004\006Arg\010Data\005\205\006\213\002\204\203\005@\205\000\204\205\002\203\005@\006\030Inputs(Parameters\034Program\013?\004)\251\000?\002\002\000\n\000)\251\000?\006\002\000\026)\211\002?\014\215?\n FromFlow\000)\211\006?\032\215\233\002?\010?\010?\n$NarrowMap\000)\211\006? \215\205\002?\010\006?$?\010$ExpandMap\000)\211\006?&\215?\000?\000?$\014Map\000)\211\002?*?\002\030ToFlow\000?\022\000\014)\251\000?\000\002\000\010\005?$?4\000\004\016)\251\000?$\002\000\004)\211\004?\010?$\203\004\030Member\000?<\003?>\000\002\006\000\020)\251\000?\010\002\000\002\005?\n?J\000\004\022\000\024\001/b\202\002\010\246\240\014\022\373\001\037\004\006Arg\010Data\005\205\006\213\002\204\203\005@\205\000\204\205\002\203\005@\006\030Inputs(Parameters\034Program\013?\004)\251\000?\002\002\000\n\000)\251\000?\006\002\000\026)\211\002?\014\215?\n FromFlow\000)\211\006?\032\...} SUCCESS
2025-12-09T03:46:12.807834Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b431012, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:46:12.808554Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:12.808560Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 1
2025-12-09T03:46:12.808919Z :FQ_PENDING_FETCHER DEBUG: pending_fetcher.cpp:361: NewTask: Scope: yandexcloud://my_folder Id: ptqudqgd0e9tq55enbt0 UserId: root@builtin AuthToken: **** (B6C6F477)
2025-12-09T03:46:12.809254Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3dc69888-647dc2a0-292de72d-727b431013, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:46:12.809370Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:375: QueryId: ptqudqgd0e9tq55enbt0 Start run actor. Compute state: RUNNING
2025-12-09T03:46:12.809374Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:469: QueryId: ptqudqgd0e9tq55enbt0 FillConnections
2025-12-09T03:46:12.809410Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:2333: QueryId: ptqudqgd0e9tq55enbt0 Run actors params: { QueryId: ptqudqgd0e9tq55enbt0 CloudId: mock_cloud UserId: root@builtin Owner: 3dc69888-647dc2a0-292de72d-727b431012 PreviousQueryRevision: 5 Connections: 1 Bindings: 0 AccountIdSignatures: 0 QueryType: STREAMING ExecuteMode: RUN ResultId: ptrudqgcvjhflbjop5h4 StateLoadMode: FROM_LAST_CHECKPOINT StreamingDisposition: { from_last_checkpoint { } } Status: RUNNING DqGraphs: 1 DqGraphIndex: 0 Resource.TopicConsumers: 2 }
2025-12-09T03:46:12.809448Z :FQ_PINGER TRACE: pinger.cpp:171: QueryId: ptqudqgd0e9tq55enbt0, Owner: 3dc69888-647dc2a0-292de72d-727b431012 Start Pinger
2025-12-09T03:46:12.809484Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:911: QueryId: ptqudqgd0e9tq55enbt0 Overall dq tasks: 2
2025-12-09T03:46:12.809550Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudqgd0e9tq55enbt0, Owner: 3dc69888-647dc2a0-292de72d-727b431012 Forward ping request: { resources { rate_limiter: READY compilation: READY topic_consumers_state: READY rate_limiter_path: "local/RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839/rate_limiter" topic_consumers { database_id: "FakeDatabaseId" database: "local" topic_path: "continue_1_input" consumer_name: "ptqudqgd0e9tq55enbt0" cluster_endpoint: "localhost:31865" token_name: "cluster:default_yds" add_bearer_to_token: true } topic_consumers { database_id: "FakeDatabaseId" database: "local" topic_path: "continue_2_input" consumer_name: "ptqudqgd0e9tq55enbt0" cluster_endpoint: "localhost:31865" token_name: "cluster:default_yds" add_bearer_to_token: true } } }
2025-12-09T03:46:12.809555Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudqgd0e9tq55enbt0, Owner: 3dc69888-647dc2a0-292de72d-727b431012 Forward request Private::PingTask
2025-12-09T03:46:12.809593Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1598: QueryId: ptqudqgd0e9tq55enbt0 ResultWriter was NOT CREATED since ResultType is empty
2025-12-09T03:46:12.809658Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.809 DEBUG ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] task_controller_impl.h:86: _RowsLimitPerWrite = 0
2025-12-09T03:46:12.809819Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1652: QueryId: ptqudqgd0e9tq55enbt0 Executer: [1:7581699492591860454:2285], Controller: [1:7581699492591860456:2287], ResultIdActor: [1:7581699492591860454:2285]
2025-12-09T03:46:12.809856Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.809 DEBUG ydbd(pid=1700716, tid=0x00007F23420BF640) [DQ] executer_actor.cpp:140: {ptqudqgd0e9tq55enbt0} TDqExecuter::OnGraph
2025-12-09T03:46:12.809921Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b431013, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 69 bytes
2025-12-09T03:46:12.809978Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3dc69888-647dc2a0-292de72d-727b431013"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:46:12.810096Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqgd0e9tq55enbt0, Owner: 3dc69888-647dc2a0-292de72d-727b431012, Request CP::PingTask with size: 404 bytes
2025-12-09T03:46:12.810171Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "3dc69888-647dc2a0-292de72d-727b431012"
query_id {
  value: "ptqudqgd0e9tq55enbt0"
}
resources {
  rate_limiter: READY
  compilation: READY
  topic_consumers_state: READY
  rate_limiter_path: "local/RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839/rate_limiter"
  topic_consumers {
    database_id: "FakeDatabaseId"
    database: "local"
    topic_path: "continue_1_input"
    consumer_name: "ptqudqgd0e9tq55enbt0"
    cluster_endpoint: "localhost:31865"
    token_name: "cluster:default_yds"
    add_bearer_to_token: true
  }
  topic_consumers {
    database_id: "FakeDatabaseId"
    database: "local"
    topic_path: "continue_2_input"
    consumer_name: "ptqudqgd0e9tq55enbt0"
    cluster_endpoint: "localhost:31865"
    token_name: "cluster:default_yds"
    add_bearer_to_token: true
  }
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765338372
  nanos: 770910000
}
tenant: "/default"
}
2025-12-09T03:46:12.810544Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.810 INFO  ydbd(pid=1700716, tid=0x00007F23420BF640) [DQ] executer_actor.cpp:157: {ptqudqgd0e9tq55enbt0} Trying to allocate 2 workers [EnableComputeActor=1]
2025-12-09T03:46:12.811599Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.811 DEBUG ydbd(pid=1700716, tid=0x00007F23420BF640) [DQ] executer_actor.cpp:247: {ptqudqgd0e9tq55enbt0} Dq timeouts are set to: 0.000000s (global), 600.000000s (workers allocation fail), 30.000000s (workers allocation warn) 
2025-12-09T03:46:12.812771Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:125: TEvAllocateWorkersResponse Nodes {
  ResourceId: 4294967297
  Worker {
    NodeId: 1
    Guid: "d0342403-4b9dc8b8-255fcaa8-59ab68a,ghrun-syjwtgwtdu"
  }
  Worker {
    NodeId: 1
    Guid: "d0342403-4b9dc8b8-255fcaa8-59ab68a,ghrun-syjwtgwtdu"
  }
}
2025-12-09T03:46:12.812923Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 3dc69888-647dc2a0-292de72d-727b4310 2025-12-09 03:46:12.812 DEBUG ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] resource_allocator.cpp:141: {ptqudqgd0e9tq55enbt0/[1:7581699492591860463:2294]} TEvAllocateWorkersResponse 1
2025-12-09T03:46:12.812933Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 3dc69888-647dc2a0-292de72d-727b4310 2025-12-09 03:46:12.812 DEBUG ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] resource_allocator.cpp:106: {ptqudqgd0e9tq55enbt0/[1:7581699492591860463:2294]} RequestActorIdsFromNodes 1
2025-12-09T03:46:12.812941Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 3dc69888-647dc2a0-292de72d-727b4310 2025-12-09 03:46:12.812 DEBUG ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] resource_allocator.cpp:115: {ptqudqgd0e9tq55enbt0/[1:7581699492591860463:2294]} RequestActorIdsFromNodes 1 4294967297
2025-12-09T03:46:12.812949Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 3dc69888-647dc2a0-292de72d-727b4310 2025-12-09 03:46:12.812 DEBUG ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] resource_allocator.cpp:119: {ptqudqgd0e9tq55enbt0/[1:7581699492591860463:2294]} RequestedNode: 1
2025-12-09T03:46:12.812972Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 3dc69888-647dc2a0-292de72d-727b4310 2025-12-09 03:46:12.812 DEBUG ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] resource_allocator.cpp:119: {ptqudqgd0e9tq55enbt0/[1:7581699492591860463:2294]} RequestedNode: 1
2025-12-09T03:46:12.812990Z :YQL_PROXY WARN: log.cpp:64: SessionId: 3dc69888-647dc2a0-292de72d-727b4310 2025-12-09 03:46:12.812 WARN  ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] resource_allocator.cpp:259: {ptqudqgd0e9tq55enbt0/[1:7581699492591860463:2294]} Send TEvAllocateWorkersRequest to Id:d0342403-4b9dc8b8-255fcaa8-59ab68a,ghrun-syjwtgwtdu,NodeId:1,
2025-12-09T03:46:12.813010Z :YQL_PROXY WARN: log.cpp:64: SessionId: 3dc69888-647dc2a0-292de72d-727b4310 2025-12-09 03:46:12.813 WARN  ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] resource_allocator.cpp:259: {ptqudqgd0e9tq55enbt0/[1:7581699492591860463:2294]} Send TEvAllocateWorkersRequest to Id:d0342403-4b9dc8b8-255fcaa8-59ab68a,ghrun-syjwtgwtdu,NodeId:1,
2025-12-09T03:46:12.814711Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:46:12.814770Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3dc69888-647dc2a0-292de72d-727b431013, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:46:12.815430Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:46:12.815433Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:46:12.835071Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765251992 nanos: 821000000 } } SUCCESS
2025-12-09T03:46:12.835120Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqgd0e9tq55enbt0, Owner: 3dc69888-647dc2a0-292de72d-727b431012, Got CP::PingTaskResponse
2025-12-09T03:46:12.835819Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqgd0e9tq55enbt0, Owner: 3dc69888-647dc2a0-292de72d-727b431012 Ping response success: { expired_at { seconds: 1765251992 nanos: 821000000 } }
2025-12-09T03:46:12.835841Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudqgd0e9tq55enbt0 Forward ping response. Success: 1. Cookie: 0
2025-12-09T03:46:12.866017Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:144: SelfId: [1:7581699492591860464:2295], TxId: ptqudqgd0e9tq55enbt0, task: 2. Ctx: {}. Start compute actor [1:7581699492591860464:2295], task: 2
2025-12-09T03:46:12.866060Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:114: SelfId: [1:7581699492591860464:2295], TxId: ptqudqgd0e9tq55enbt0, task: 2. Ctx: {}. Use CPU quota: 1. Rate limiter resource: { "local/RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839/rate_limiter", "mock_cloud/my_folder/ptqudqgd0e9tq55enbt0" }
2025-12-09T03:46:12.866167Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.866 DEBUG ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] executer_actor.cpp:358: {ptqudqgd0e9tq55enbt0} TDqExecuter::TEvAllocateWorkersResponse
2025-12-09T03:46:12.866176Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.866 DEBUG ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] executer_actor.cpp:384: {ptqudqgd0e9tq55enbt0} Allocated resource 4294967297
2025-12-09T03:46:12.866214Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.866 DEBUG ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] executer_actor.cpp:401: {ptqudqgd0e9tq55enbt0} WorkerInfo: Id:d0342403-4b9dc8b8-255fcaa8-59ab68a,ghrun-syjwtgwtdu,NodeId:1,StageId:200742
2025-12-09T03:46:12.866218Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 3dc69888-647dc2a0-292de72d-727b4310 2025-12-09 03:46:12.866 DEBUG ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Build task: 2
2025-12-09T03:46:12.866220Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.866 DEBUG ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] executer_actor.cpp:402: {ptqudqgd0e9tq55enbt0} TaskInfo: 0/1
2025-12-09T03:46:12.866227Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.866 DEBUG ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] executer_actor.cpp:404: {ptqudqgd0e9tq55enbt0}  ObjectId: 2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707, dq_vanilla_job.lite, 
2025-12-09T03:46:12.866245Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.866 DEBUG ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] executer_actor.cpp:401: {ptqudqgd0e9tq55enbt0} WorkerInfo: Id:d0342403-4b9dc8b8-255fcaa8-59ab68a,ghrun-syjwtgwtdu,NodeId:1,StageId:200761
2025-12-09T03:46:12.866250Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.866 DEBUG ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] executer_actor.cpp:402: {ptqudqgd0e9tq55enbt0} TaskInfo: 1/2
2025-12-09T03:46:12.866253Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:144: SelfId: [1:7581699492591860467:2296], TxId: ptqudqgd0e9tq55enbt0, task: 1. Ctx: {}. Start compute actor [1:7581699492591860467:2296], task: 1
2025-12-09T03:46:12.866254Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.866 DEBUG ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] executer_actor.cpp:404: {ptqudqgd0e9tq55enbt0}  ObjectId: 2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707, dq_vanilla_job.lite, 
2025-12-09T03:46:12.866263Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudqgd0e9tq55enbt0 2025-12-09 03:46:12.866 INFO  ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] executer_actor.cpp:415: {ptqudqgd0e9tq55enbt0} 2 workers allocated
2025-12-09T03:46:12.866273Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:114: SelfId: [1:7581699492591860467:2296], TxId: ptqudqgd0e9tq55enbt0, task: 1. Ctx: {}. Use CPU quota: 1. Rate limiter resource: { "local/RateLimiter_9e3d4ab0-4c3c-4175-a077-153e07479839/rate_limiter", "mock_cloud/my_folder/ptqudqgd0e9tq55enbt0" }
2025-12-09T03:46:12.866344Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 03:46:12.866 TRACE ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] task_controller_impl.h:271:  [1:7581699492591860456:2287] ExportStats Summary
2025-12-09T03:46:12.866366Z :YQL_PROXY DEBUG: log.cpp:64: SessionId:  2025-12-09 03:46:12.866 DEBUG ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] task_controller_impl.h:526: Ready State: [1:7581699492591860456:2287]
2025-12-09T03:46:12.866374Z :YQL_PROXY DEBUG: log.cpp:64: SessionId:  2025-12-09 03:46:12.866 DEBUG ydbd(pid=1700716, tid=0x00007F23410BD640) [DQ] task_controller_impl.h:537: Forward: [1:7581699492591860456:2287] to [1:7581699492591860455:2286]
2025-12-09T03:46:12.866399Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:70: [ptqudqgd0e9tq55enbt0-0.5] TEvReadyState, streaming disposition { from_last_checkpoint { } }, state load mode FROM_LAST_CHECKPOINT, checkpointing period 1.000000s
2025-12-09T03:46:12.866409Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:104: [ptqudqgd0e9tq55enbt0-0.5] AllActors count: 2, ActorsToTrigger count: 2, ActorsToNotify count: 2, ActorsToWaitFor count: 2
2025-12-09T03:46:12.866410Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:114: [ptqudqgd0e9tq55enbt0-0.5] Send TEvRegisterCoordinatorRequest
2025-12-09T03:46:12.866416Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:261: [ptqudqgd0e9tq55enbt0-0.5] Got TEvRegisterCoordinatorRequest
2025-12-09T03:46:12.866417Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:243: StartInitialization, EnableSecureScriptExecutions: 0
2025-12-09T03:46:12.866425Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 3dc69888-647dc2a0-292de72d-727b4310 2025-12-09 03:46:12.866 DEBUG ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] tasks_runner_local.cpp:267: {TODO/2} task: 2, program size: 36, llvm: ``.
2025-12-09T03:46:12.866886Z :STREAMS_STORAGE_SERVICE NOTICE: storage_proxy.cpp:585: Add to delayed
2025-12-09T03:46:12.882026Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:557: Checkpoint storage and state storage were successfully initted
2025-12-09T03:46:12.882040Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:261: [ptqudqgd0e9tq55enbt0-0.5] Got TEvRegisterCoordinatorRequest
2025-12-09T03:46:12.930078Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:279: [ptqudqgd0e9tq55enbt0-0.5] Graph registered
2025-12-09T03:46:12.930086Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:281: [ptqudqgd0e9tq55enbt0-0.5] Send TEvRegisterCoordinatorResponse
2025-12-09T03:46:12.930181Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:131: [ptqudqgd0e9tq55enbt0-0.5] Got TEvRegisterCoordinatorResponse; issues: 
2025-12-09T03:46:12.930186Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:140: [ptqudqgd0e9tq55enbt0-0.5] Successfully registered in storage
2025-12-09T03:46:12.930188Z :STREAMS_CHECKPOINT_COORDINATOR INFO: checkpoint_coordinator.cpp:141: [ptqudqgd0e9tq55enbt0-0.5] Send TEvNewCheckpointCoordinator to 2 actor(s)
2025-12-09T03:46:12.930195Z :STREAMS_CHECKPOINT_COORDINATOR INFO: checkpoint_coordinator.cpp:149: [ptqudqgd0e9tq55enbt0-0.5] Send TEvGetCheckpointsMetadataRequest; state load mode: FROM_LAST_CHECKPOINT; load graph: 0
2025-12-09T03:46:12.930201Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:434: [ptqudqgd0e9tq55enbt0-0] Got TEvGetCheckpointsMetadataRequest
2025-12-09T03:46:12.998345Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:451: [ptqudqgd0e9tq55enbt0-0] Send TEvGetCheckpointsMetadataResponse
2025-12-09T03:46:12.998418Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:186: [ptqudqgd0e9tq55enbt0-0.5] Got TEvGetCheckpointsMetadataResponse
2025-12-09T03:46:12.998425Z :STREAMS_CHECKPOINT_COORDINATOR INFO: checkpoint_coordinator.cpp:219: [ptqudqgd0e9tq55enbt0-0.5] Will restore from checkpoint 4:2
2025-12-09T03:46:13.038867Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 3dc69888-647dc2a0-292de72d-727b4310 2025-12-09 03:46:13.038 DEBUG ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Build task: 2 takes 172610 us
2025-12-09T03:46:13.038887Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 3dc69888-647dc2a0-292de72d-727b4310 2025-12-09 03:46:13.038 DEBUG ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Prepare task: 2
2025-12-09T03:46:13.038911Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 3dc69888-647dc2a0-292de72d-727b4310 2025-12-09 03:46:13.038 DEBUG ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Prepare task: 2, takes 18 us
2025-12-09T03:46:13.038983Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:197: [] Task: 2. Got TEvNewCheckpointCoordinator event: generation 5, actorId: [1:7581699492591860455:2286]
2025-12-09T03:46:13.038985Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:208: [] Task: 2. Assign checkpoint coordinator (generation = 5)
2025-12-09T03:46:13.039546Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1360: SelfId: [1:7581699492591860464:2295], TxId: ptqudqgd0e9tq55enbt0, task: 2. Ctx: {}. Create source for input 0 { Source { Type: "PqSource" Settings { type_url: "type.googleapis.com/NYql.NPq.NProto.TDqPqTopicSource" value: "\n\020continue_2_input\022\024ptqudqgd0e9tq55enbt0\032\017localhost:31865\"\025\n\023cluster:default_yds*\005local0\002@\001J\016FakeDatabaseIdb\004Dataj\025[\"DataType\";\"String\"]\222\001\024ptcudqgd0emir08c6gg2\232\001\003raw\242\001/[\"StructType\";[[\"Data\";[\"DataType\";\"String\"]]]]\262\001\004*\002\010\001\272\001\026\n\010cloud_id\022\nmock_cloud\272\001 \n\005scope\022\027yandexcloud://my_folder" } WatermarksMode: WATERMARKS_MODE_DISABLED } }
2025-12-09T03:46:13.039815Z :KQP_COMPUTE DEBUG: dq_pq_read_actor_base.cpp:153: SelfId: [0:0:0], TxId: ptqudqgd0e9tq55enbt0, task: 2. PQ source. SessionId: empty Watermarks enabled: 0 granularity: 0.000000s late arrival delay: 0.000000s idle: 0 idle timeout: 0.000000s
2025-12-09T03:46:13.039830Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1447: SelfId: [1:7581699492591860464:2295], TxId: ptqudqgd0e9tq55enbt0, task: 2. Ctx: {}. Create sink for output 0 { Sink { Type: "PqSink" Settings { type_url: "type.googleapis.com/NYql.NPq.NProto.TDqPqTopicSink" value: "\n\021continue_2_output\022\017localhost:31865\032\025\n\023cluster:default_yds\"\005local0\0018\002" } } }
2025-12-09T03:46:13.039902Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:197: [] Task: 1. Got TEvNewCheckpointCoordinator event: generation 5, actorId: [1:7581699492591860455:2286]
2025-12-09T03:46:13.039905Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:208: [] Task: 1. Assign checkpoint coordinator (generation = 5)
2025-12-09T03:46:13.039933Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:265: [ptqudqgd0e9tq55enbt0-0] Task: 2. [Checkpoint 4.2] TEvRestoreFromCheckpoint, StateLoadPlan = { StateType: STATE_TYPE_OWN }
2025-12-09T03:46:13.039933Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1360: SelfId: [1:7581699492591860467:2296], TxId: ptqudqgd0e9tq55enbt0, task: 1. Ctx: {}. Create source for input 0 { Source { Type: "PqSource" Settings { type_url: "type.googleapis.com/NYql.NPq.NProto.TDqPqTopicSource" value: "\n\020continue_1_input\022\024ptqudqgd0e9tq55enbt0\032\017localhost:31865\"\025\n\023cluster:default_yds*\005local0\002@\001J\016FakeDatabaseIdb\004Dataj\025[\"DataType\";\"String\"]\222\001\024ptcudqgd0emir08c6gg2\232\001\003raw\242\001/[\"StructType\";[[\"Data\";[\"DataType\";\"String\"]]]]\262\001\004*\002\010\001\272\001\026\n\010cloud_id\022\nmock_cloud\272\001 \n\005scope\022\027yandexcloud://my_folder" } WatermarksMode: WATERMARKS_MODE_DISABLED } }
2025-12-09T03:46:13.039968Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 03:46:13.039 TRACE ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] task_controller_impl.h:160: [1:7581699492591860456:2287] EvState TaskId: 2 State: 2 PingCookie: 0 StatusCode: UNSPECIFIED
2025-12-09T03:46:13.039975Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 03:46:13.039 TRACE ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] task_controller_impl.h:201:  [1:7581699492591860456:2287] Executing TaskId: 2
2025-12-09T03:46:13.039984Z :KQP_COMPUTE DEBUG: dq_pq_read_actor_base.cpp:153: SelfId: [0:0:0], TxId: ptqudqgd0e9tq55enbt0, task: 1. PQ source. SessionId: empty Watermarks enabled: 0 granularity: 0.000000s late arrival delay: 0.000000s idle: 0 idle timeout: 0.000000s
2025-12-09T03:46:13.039989Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:508: [ptqudqgd0e9tq55enbt0-0] [4:2] Got TEvGetTaskState: tasks {2}
2025-12-09T03:46:13.039993Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:519: [ptqudqgd0e9tq55enbt0-0] [4:2] GetState, tasks: 2
2025-12-09T03:46:13.039994Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1447: SelfId: [1:7581699492591860467:2296], TxId: ptqudqgd0e9tq55enbt0, task: 1. Ctx: {}. Create sink for output 0 { Sink { Type: "PqSink" Settings { type_url: "type.googleapis.com/NYql.NPq.NProto.TDqPqTopicSink" value: "\n\021continue_2_output\022\017localhost:31865\032\025\n\023cluster:default_yds\"\005local0\0018\002" } } }
2025-12-09T03:46:13.040016Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:265: [ptqudqgd0e9tq55enbt0-0] Task: 1. [Checkpoint 4.2] TEvRestoreFromCheckpoint, StateLoadPlan = { StateType: STATE_TYPE_OWN }
2025-12-09T03:46:13.040021Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:508: [ptqudqgd0e9tq55enbt0-0] [4:2] Got TEvGetTaskState: tasks {1}
2025-12-09T03:46:13.040022Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:519: [ptqudqgd0e9tq55enbt0-0] [4:2] GetState, tasks: 1
2025-12-09T03:46:13.040038Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 03:46:13.040 TRACE ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] task_controller_impl.h:160: [1:7581699492591860456:2287] EvState TaskId: 1 State: 2 PingCookie: 0 StatusCode: UNSPECIFIED
2025-12-09T03:46:13.040044Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 03:46:13.040 TRACE ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] task_controller_impl.h:201:  [1:7581699492591860456:2287] Executing TaskId: 1
2025-12-09T03:46:13.040049Z :YQL_PROXY DEBUG: log.cpp:64: SessionId:  2025-12-09 03:46:13.040 DEBUG ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] task_controller_impl.h:561: Update channels
2025-12-09T03:46:13.040391Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 03:46:13.040 TRACE ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] task_controller_impl.h:577: 1 
2025-12-09T03:46:13.040403Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 03:46:13.040 TRACE ydbd(pid=1700716, tid=0x00007F23438C2640) [DQ] task_controller_impl.h:577: 2 
2025-12-09T03:46:13.040410Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1064: SelfId: [1:7581699492591860467:2296], TxId: ptqudqgd0e9tq55enbt0, task: 1. Ctx: {}. Received channels info: 
2025-12-09T03:46:13.040412Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1064: SelfId: [1:7581699492591860464:2295], TxId: ptqudqgd0e9tq55enbt0, task: 2. Ctx: {}. Received channels info: 
2025-12-09T03:46:13.056506Z :TICKET_PARSER DEBUG: ticket_parser_impl.h:1875: Ticket **** (B6C6F477) (ipv6:[::1]:44160) has now valid token of root@builtin
2025-12-09T03:46:13.056590Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd0e9tq55enbt0"
2025-12-09T03:46:13.056615Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7581699496886827776:2300]
2025-12-09T03:46:13.056624Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-09T03:46:13.056634Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7581699496886827777:2301]
2025-12-09T03:46:13.056640Z :FQ_QUOTA_SERVICE DEBUG: quota_manager.cpp:255: cloud.mock_cloud NOT CASHED, Loading ...
2025-12-09T03:46:13.068383Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:441: cloud.mock_cloud {yq.analyticsQuery.count=0/100, yq.cpuPercent.count=0/10000, yq.streamingQuery.count=1/100} FROM DB
2025-12-09T03:46:13.068411Z :FQ_QUOTA_SERVICE DEBUG: quota_manager.cpp:269: 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=1/100} LOADED
2025-12-09T03:46:13.068414Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-09T03:46:13.068424Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-09T03:46:13.068427Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-09T03:46:13.068454Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-09T03:46:13.075174Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=0/100 REFRESHED
2025-12-09T03:46:13.075196Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=1/100 REFRESHED
2025-12-09T03:46:13.075213Z :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=1/100} SEND QUOTAS
2025-12-09T03:46:13.075224Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-09T03:46:13.075257Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd0e9tq55enbt0"
2025-12-09T03:46:13.075408Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7581699496886827783:2307]
2025-12-09T03:46:13.075441Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-09T03:46:13.075464Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd0e9tq55enbt0"
2025-12-09T03:46:13.075478Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7581699496886827784:2308]
2025-12-09T03:46:13.075507Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudqgd0e9tq55enbt0"
} [root@builtin, **** (B6C6F477)] 
2025-12-09T03:46:13.076956Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-09T03:46:13.078503Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudqgd0e9tq55enbt0" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1765251944 nanos: 745041000 } modified_at { seconds: 1765251950 nanos: 406017000 } revision: 4 } started_at { seconds: 1765251950 nanos: 904000000 } execute_mode: RUN status: RUNNING last_job_query_revision: 4 last_job_id: "ptjudqgd08t5i4oh6p8n-ptqudqgd0e9tq55enbt0" started_by: "root@builtin" has_saved_checkpoints: true submitted_at { seconds: 1765251950 nanos: 406017000 } } content { type: STREAMING name: "continue-from-offsets-query" acl { visibility: PRIVATE } text: "\n            PRAGMA dq.MaxTasksPerStage=\"1\";\n\n            INSERT INTO yds.`continue_2_output`\n            SELECT Data FROM yds.`continue_1_input`;\n\n            INSERT INTO yds.`continue_2_output`\n            SELECT Data FROM yds.`continue_2_input`;" } plan { json: "{\"Detailed\":{\"Operations\":[{\"Id\":3,\"Name\":\"DqStage\",\"Streams\":{\"Program\":[{\"Name\":\"DqSourceWideWrap\"},{\"Name\":\"NarrowMap\"}]}},{\"Id\":14,\"Name\":\"DqQuery!\",\"DependsOn\":[3]},{\"Id\":11,\"Name\":\"DqStage\",\"Streams\":{\"Program\":[{\"Name\":\"DqSourceWideWrap\"},{\"Name\":\"NarrowMap\"}]},\"DependsOn\":[14]},{\"Id\":2,\"Name\":\"DqQuery!\",\"DependsOn\":[3,11]},{\"Id\":1,\"Name\":\"Commit!\",\"DependsOn\":[2]}],\"OperationRoot\":1,\"Providers\":[],\"OperationStats\":{\"Commit!\":1,\"DqQuery!\":2,\"DqStage\":2}},\"Basic\":{\"nodes\":[{\"id\":3,\"level\":1,\"name\":\"DqStage #3\",\"type\":\"op\"},{\"id\":14,\"level\":2,\"name\":\"DqQuery!\",\"type\":\"op\"},{\"id\":11,\"level\":3,\"name\":\"DqStage #11\",\"type\":\"op\"},{\"id\":2,\"level\":4,\"name\":\"DqQuery!\",\"type\":\"op\"},{\"id\":1,\"level\":5,\"name\":\"Commit!\",\"type\":\"op\"}],\"links\":[{\"source\":3,\"target\":14},{\"source\":14,\"target\":11},{\"source\":3,\"target\":2},{\"source\":11,\"target\":2},{\"source\":2,\"target\":1}]}}" } transient_issue { message: "Topic `continue_2_input` is not found in previous query. Query will use fresh offsets for its partitions" issue_code: 3 severity: 2 } statistics { json: "{\"Graph=0\":{\"Tasks\":{\"sum\":2,\"count\":2,\"avg\":1,\"max\":1,\"min\":1},\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":2,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"}}}" } ast { data: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (Configure! $1 (DataSource \'\"dq\" \'\"$all\") \'\"Attr\" \'\"maxtasksperstage\" \'\"1\"))\n(let $3 (DataSource \'\"pq\" \'\"yds\"))\n(let $4 \'(\'(\'\"PartitionsCount\" \'\"2\")))\n(let $5 (StructType \'(\'\"Data\" (DataType \'String))))\n(let $6 (PqTopic \'\"yds\" \'\"local\" \'\"continue_1_input\" $4 \'() $5))\n(let $7 \'(\'\"Data\"))\n(let $8 \'(\'\"Endpoint\" \'\"localhost:31865\"))\n(let $9 \'(\'\"SharedReading\" \'\"0\"))\n(let $10 \'(\'\"AddBearerToToken\" \'\"1\"))\n(let $11 \'($8 $9 \'(\'\"ReconnectPeriod\" \'\"\") \'(\'\"Format\" \'\"raw\") \'(\'\"ReadGroup\" \'\"ptcudqgd0emir08c6gg2\") $10))\n(let $12 (SecureParam \'\"cluster:default_yds\"))\n(let $13 (DqPqTopicSource $2 $6 $7 $11 $12 \'\"\" $5 \'\"\"))\n(let $14 (lambda \'($23) (block \'(\n  (let $24 \'(\'(\'\"format\" \'\"raw\") \'(\'\"formatSettings\" \'(\'(\'\"data.datetime.formatname\" \'\"POSIX\") \'(\'\"data.timestamp.formatname\" \'\"POSIX\"))) \'(\'\"settings\" \'($9))))\n  (let $25 (DqSourceWideWrap $23 $3 $5 $24))\n  (return (NarrowMap $25 (lambda \'($26) (AsStruct \'(\'\"Data\" $26)))))\n))))\n(let $15 (DataSink \'\"pq\" \'\"yds\"))\n(let $16 (PqTopic \'\"yds\" \'\"local\" \'\"continue_2_output\" $4 \'() $5))\n(let $17 (DqPqTopicSink $16 \'($8 $10) $12))\n(let $18 \'((DqSink \'\"0\" $15 $17)))\n(let $19 (DqStage \'((DqSource $3 $13)) $14 \'(\'(\'\"_logical_id\" \'200572)) $18))\n(let $20 (PqTopic \'\"yds\" \'\"local\" \'\"continue_2_input\" $4 \'() $5))\n(let $21 (DqPqTopicSource (DqQuery! $2 \'($19)) $20 $7 $11 $12 \'\"\" $5 \'\"\"))\n(let $22 (DqStage \'((DqSource $3 $21)) $14 \'(\'(\'\"_logical_id\" \'200608)) $18))\n(return (Commit! (DqQuery! $2 \'($19 $22)) $15))\n)\n" } } } SUCCESS
2025-12-09T03:46:13.082799Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=1/100 NOTIFY CHANGE
2025-12-09T03:46:13.083063Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=0/100 NOTIFY CHANGE
