2025-10-26 19:55:22.726 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [default] dqrun.cpp:756: dqrun ABI version: 2.38.0
2025-10-26 19:55:22.809 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [default] storage.cpp:177: FileStorage initialized in "/home/runner/.ya/build/build_root/70hy/0003ad/r3tmp/tmpxd9ZxV/", temporary dir: "/home/runner/.ya/build/build_root/70hy/0003ad/r3tmp/tmpxd9ZxV/2000036", files: 0, total size: 0
2025-10-26 19:55:22.813 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [YT] init.cpp:24: Entering YT API init
2025-10-26 19:55:22.813 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [YT] init.cpp:40: Using YT global prefix: //
2025-10-26 19:55:22.836 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [generic] client.cpp:34: Connector endpoint: grpc://localhost:32779
2025-10-26 19:55:22.856 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:120: Initializing local services
2025-10-26 19:55:22.856 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:147: Initializing node table
2025-10-26 19:55:22.856 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:177: IC Handshake set to 5.000000s
2025-10-26 19:55:22.856 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:178: IC DeadPeer set to 30.000000s
2025-10-26 19:55:22.856 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:179: IC CloseOnIdle set to 300.000000s
2025-10-26 19:55:22.856 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:181: IC SendBufferDieLimitInMB set to 512
2025-10-26 19:55:22.856 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:182: IC TotalInflightAmountOfData set to 0
2025-10-26 19:55:22.856 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:183: IC MergePerPeerCounters set to 0
2025-10-26 19:55:22.856 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:184: IC MergePerDataCenterCounters set to 0
2025-10-26 19:55:22.857 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:185: IC TCPSocketBufferSize set to 16777216
2025-10-26 19:55:22.857 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:187: IC PingPeriod set to 3.000000s
2025-10-26 19:55:22.857 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:188: IC ForceConfirmPeriod set to 1.000000s
2025-10-26 19:55:22.857 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:189: IC LostConnection set to 0.000000s
2025-10-26 19:55:22.857 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:190: IC BatchPeriod set to 0.000000s
2025-10-26 19:55:22.857 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:192: IC MessagePendingTimeout set to 5.000000s
2025-10-26 19:55:22.857 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:194: IC MessagePendingSize set to 18446744073709551615
2025-10-26 19:55:22.857 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:195: IC MaxSerializedEventSize set to 67108000
2025-10-26 19:55:22.857 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:196: IC EnableExternalDataChannel set to 0
2025-10-26 19:55:22.857 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:201: Initializing proxy actors
2025-10-26 19:55:22.857 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:212: Start listener
2025-10-26 19:55:22.857 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:215: Start listener ::1:31337 socket: 11
2025-10-26 19:55:22.857 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] interconnect_helpers.cpp:228: Actor initialization complete
2025-10-26 19:55:22.896 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] service_node.cpp:126: Starting GRPC on 31338
GRpc memory quota was set but disabled due to issues with grpc quoter, to enable it use EnableGRpcMemoryQuota option
2025-10-26 19:55:22.901 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] service_node.cpp:33: Start GRPC Listener
Parse SQL...
Compile program...
2025-10-26 19:55:25.707 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [generic] yql_generic_settings.cpp:38: GenericConfiguration::AddCluster: name = clickhouse_integration_test, kind = CLICKHOUSE, database name = constant_NATIVE, database id = , endpoint = { host: "172.18.0.2" port: 9000 }, use tls = 0, protocol = NATIVE
Run program...
2025-10-26 19:55:25.710 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] yql_dq_provider.cpp:109: OpenSession dummy_op
2025-10-26 19:55:25.710 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] yql_dq_gateway.cpp:565: {dummy_op} OpenSession
2025-10-26 19:55:25.711 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] yql_dq_provider.cpp:112: OpenSession dummy_op complete in 0
2025-10-26 19:55:25.713 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core eval] yql_eval_expr.cpp:403: {dummy_op} EvaluateExpression - start
2025-10-26 19:55:25.714 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core eval] yql_eval_expr.cpp:1165: {dummy_op} EvaluateExpression - finish
2025-10-26 19:55:25.716 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [YT] yql_yt_io_discovery.cpp:227: {dummy_op} YtIODiscovery - finish, status: Ok
2025-10-26 19:55:25.718 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [generic] yql_generic_load_meta.cpp:83: {dummy_op} Loading table meta for: `clickhouse_integration_test`.`tef4e2b06`
2025-10-26 19:55:25.722 DEBUG dqrun(pid=2000036, tid=0x00007FAC0BD19640) [DQ] grpc_service.cpp:577: OpenSession for dummy_op runner
2025-10-26 19:55:25.723 INFO  dqrun(pid=2000036, tid=0x00007FAC083B9640) [DQ] yql_dq_gateway.cpp:593: {dummy_op} OpenSession OK
2025-10-26 19:55:25.730 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [RESULT] yql_result_provider.cpp:1414: {dummy_op} RewriteIO
2025-10-26 19:55:25.735 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core] type_ann_expr.cpp:134: {dummy_op} Repeated callable stats:
RemovePrefixMembers=1
TypeOf=1
SqlProject=1
GenReadTable!=1
PersistableRepr=1
SqlProjectItem=1
ResWrite!=1

2025-10-26 19:55:25.735 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4.23ms
2025-10-26 19:55:25.735 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 172us
2025-10-26 19:55:25.736 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 340us
2025-10-26 19:55:25.736 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:55:25.736 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 228us
2025-10-26 19:55:25.736 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 69us
2025-10-26 19:55:25.737 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 333us
2025-10-26 19:55:25.738 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core] yql_co_simple1.cpp:6127: {dummy_op} Expand SqlProject
2025-10-26 19:55:25.739 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 395us
2025-10-26 19:55:25.740 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 112us
2025-10-26 19:55:25.740 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:55:25.740 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 216us
2025-10-26 19:55:25.740 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 66us
2025-10-26 19:55:25.741 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 247us
2025-10-26 19:55:25.741 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core] yql_opt_utils.cpp:764: {dummy_op} Enumerate struct literal for FlattenMembers
2025-10-26 19:55:25.742 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 227us
2025-10-26 19:55:25.742 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 58us
2025-10-26 19:55:25.742 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:55:25.742 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 116us
2025-10-26 19:55:25.742 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 48us
2025-10-26 19:55:25.743 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 225us
2025-10-26 19:55:25.743 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core] yql_co_simple1.cpp:2016: {dummy_op} FlatMap with single arg AsList
2025-10-26 19:55:25.743 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 209us
2025-10-26 19:55:25.744 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 59us
2025-10-26 19:55:25.744 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:55:25.744 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 105us
2025-10-26 19:55:25.744 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 49us
2025-10-26 19:55:25.744 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 180us
2025-10-26 19:55:25.746 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core] yql_co_flow2.cpp:1252: {dummy_op} FlatMapSubsetFields
2025-10-26 19:55:25.747 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 187us
2025-10-26 19:55:25.747 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 58us
2025-10-26 19:55:25.747 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 3us
2025-10-26 19:55:25.747 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 83us
2025-10-26 19:55:25.747 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 47us
2025-10-26 19:55:25.747 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 131us
2025-10-26 19:55:25.748 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] yql_dq_recapture.cpp:102: {dummy_op} DqsRecapture
2025-10-26 19:55:25.749 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [generic] yql_generic_datasource_type_ann.cpp:135: {dummy_op} struct column order0: _yql_block_length(Scalar<Uint64>), 1: col(Block<Int64>)
2025-10-26 19:55:25.749 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 260us
2025-10-26 19:55:25.749 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 120us
2025-10-26 19:55:25.749 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 38us
2025-10-26 19:55:25.749 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 216us
2025-10-26 19:55:25.751 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [generic] yql_optimize.cpp:135: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSourceWrap
2025-10-26 19:55:25.752 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [generic] yql_generic_datasource_type_ann.cpp:135: {dummy_op} struct column order0: _yql_block_length(Scalar<Uint64>)
2025-10-26 19:55:25.752 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 199us
2025-10-26 19:55:25.752 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 79us
2025-10-26 19:55:25.752 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 35us
2025-10-26 19:55:25.752 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 154us
2025-10-26 19:55:25.755 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [generic] yql_optimize.cpp:135: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-10-26 19:55:25.755 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 92us
2025-10-26 19:55:25.755 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 38us
2025-10-26 19:55:25.755 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 36us
2025-10-26 19:55:25.755 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 136us
2025-10-26 19:55:25.757 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] yql_optimize.cpp:135: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-10-26 19:55:25.758 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 512us
2025-10-26 19:55:25.758 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 176us
2025-10-26 19:55:25.758 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 3us
2025-10-26 19:55:25.759 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 159us
2025-10-26 19:55:25.759 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 64us
2025-10-26 19:55:25.759 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 259us
2025-10-26 19:55:25.763 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] yql_optimize.cpp:135: {dummy_op} DqsPhy-BuildFlatmapStage<false>
2025-10-26 19:55:25.764 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 478us
2025-10-26 19:55:25.764 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 105us
2025-10-26 19:55:25.764 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:55:25.764 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 143us
2025-10-26 19:55:25.765 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:55:25.765 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 139us
2025-10-26 19:55:25.765 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 113us
2025-10-26 19:55:25.765 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 288us
2025-10-26 19:55:25.771 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [RESULT] yql_result_provider.cpp:769: {dummy_op} ResPull
2025-10-26 19:55:25.772 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 295us
2025-10-26 19:55:25.772 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 118us
2025-10-26 19:55:25.772 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:55:25.773 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 124us
2025-10-26 19:55:25.773 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 129us
2025-10-26 19:55:25.773 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 544us
2025-10-26 19:55:25.779 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:59: {dummy_op} Begin, root #199356
2025-10-26 19:55:25.779 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:72: {dummy_op} Collect unused nodes for root #199356, status: Ok
2025-10-26 19:55:25.779 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:577: {dummy_op} Node #199031<Configure!> finished execution
2025-10-26 19:55:25.780 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:594: {dummy_op} Node #199031<Configure!> created 0 trackable nodes: 
2025-10-26 19:55:25.780 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:577: {dummy_op} Node #199352<DqCnResult> finished execution
2025-10-26 19:55:25.780 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:594: {dummy_op} Node #199352<DqCnResult> created 0 trackable nodes: 
2025-10-26 19:55:25.780 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] yql_dq_exectransformer.cpp:1247: {dummy_op/#199354/#199369} Executing Pull (UniqueId=199369)
2025-10-26 19:55:25.781 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 30us
2025-10-26 19:55:25.781 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 23us
2025-10-26 19:55:25.781 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199354/#199369} Execution of [UpdateCompletness] took 92us
2025-10-26 19:55:25.782 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199354/#199369} Execution of [EliminateCommonSubExpressionsForSubGraph] took 260us
2025-10-26 19:55:25.905 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 890us
2025-10-26 19:55:25.905 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 213us
2025-10-26 19:55:25.905 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:55:25.905 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 220us
2025-10-26 19:55:25.905 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:55:25.905 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 79us
2025-10-26 19:55:25.906 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199354/#199369} Execution of [UpdateCompletness] took 134us
2025-10-26 19:55:25.906 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199354/#199369} Execution of [EliminateCommonSubExpressionsForSubGraph] took 330us
2025-10-26 19:55:25.910 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core] yql_co_flow2.cpp:1012: {dummy_op/#199354/#199369} Drop FromFlow with ToFlow
2025-10-26 19:55:25.911 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 241us
2025-10-26 19:55:25.911 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 70us
2025-10-26 19:55:25.911 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 6us
2025-10-26 19:55:25.911 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 100us
2025-10-26 19:55:25.911 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199354/#199369} Execution of [UpdateCompletness] took 90us
2025-10-26 19:55:25.912 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199354/#199369} Execution of [EliminateCommonSubExpressionsForSubGraph] took 289us
2025-10-26 19:55:25.915 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 35us
2025-10-26 19:55:25.915 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 19us
2025-10-26 19:55:25.915 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199354/#199369} Execution of [UpdateCompletness] took 87us
2025-10-26 19:55:25.916 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199354/#199369} Execution of [EliminateCommonSubExpressionsForSubGraph] took 280us
2025-10-26 19:55:25.916 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core peephole] yql_opt_peephole_physical.cpp:2365: {dummy_op/#199354/#199369} FlatMap to Map
2025-10-26 19:55:25.918 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 772us
2025-10-26 19:55:25.918 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 164us
2025-10-26 19:55:25.918 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 6us
2025-10-26 19:55:25.918 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 209us
2025-10-26 19:55:25.918 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:55:25.919 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 209us
2025-10-26 19:55:25.919 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199354/#199369} Execution of [UpdateCompletness] took 106us
2025-10-26 19:55:25.919 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199354/#199369} Execution of [EliminateCommonSubExpressionsForSubGraph] took 411us
2025-10-26 19:55:25.920 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core peephole] yql_opt_peephole_physical.cpp:3137: {dummy_op/#199354/#199369} Fuse Map with NarrowMap
2025-10-26 19:55:25.921 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 524us
2025-10-26 19:55:25.921 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 87us
2025-10-26 19:55:25.921 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:55:25.921 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 176us
2025-10-26 19:55:25.921 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199354/#199369} Execution of [UpdateCompletness] took 68us
2025-10-26 19:55:25.922 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199354/#199369} Execution of [EliminateCommonSubExpressionsForSubGraph] took 317us
2025-10-26 19:55:25.922 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core dq] yql_dq_exectransformer.cpp:376: {dummy_op/#199354/#199369} Setting block status for stage #199316 to Partial
2025-10-26 19:55:25.923 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 231us
2025-10-26 19:55:25.923 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 142us
2025-10-26 19:55:25.923 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199354/#199369} Execution of [UpdateCompletness] took 51us
2025-10-26 19:55:25.923 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199354/#199369} Execution of [EliminateCommonSubExpressionsForSubGraph] took 265us
2025-10-26 19:55:25.924 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core dq] yql_dq_exectransformer.cpp:376: {dummy_op/#199354/#199369} Setting block status for stage #199343 to None
2025-10-26 19:55:25.924 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199354/#199369} Execution of [TypeAnnotationTransformer::DoTransform] took 185us
2025-10-26 19:55:25.924 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199354/#199369} Execution of [ConstraintTransformer::DoTransform] took 96us
2025-10-26 19:55:25.924 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199354/#199369} Execution of [UpdateCompletness] took 72us
2025-10-26 19:55:25.925 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199354/#199369} Execution of [EliminateCommonSubExpressionsForSubGraph] took 299us
2025-10-26 19:55:25.926 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] execution_planner.cpp:152: {dummy_op/#199354/#199369} Execution Plan (
(let $1 '"clickhouse_integration_test")
(let $2 (DataSource '"generic" $1))
(let $3 (GenSourceSettings $1 '"tef4e2b06" (SecureParam '"cluster:default_clickhouse_integration_test") '() (lambda '($8) (Bool '"true"))))
(let $4 '('('"_logical_id" '199316) '('"_id" '"460eb62b-8bb65352-4dd7fba9-d19db116") '('"_block_status" '"Partial")))
(let $5 (DqPhyStage '((DqSource $2 $3)) (lambda '($9) (FromFlow (NarrowMap (WideFromBlocks (DqSourceWideBlockWrap $9 $2 (StructType))) (lambda '() (AsStruct '('"column0" (Int32 '"42"))))))) $4))
(let $6 '('('"_logical_id" '199343) '('"_id" '"daea73f8-61f00638-c49f2d5a-9e3f0f58") '('"_block_status" '"None")))
(let $7 (DqPhyStage '((DqCnUnionAll (TDqOutput $5 '"0"))) (lambda '($10) $10) $6))
(return (DqCnResult (TDqOutput $7 '"0") '()))
)

2025-10-26 19:55:25.927 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [generic] yql_generic_dq_integration.cpp:126: {dummy_op/#199354/#199369} Filling source settings: cluster: clickhouse_integration_test, table: tef4e2b06, endpoint: host: "172.18.0.2" port: 9000
2025-10-26 19:55:25.935 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] yql_dq_gateway.cpp:382: {dummy_op} Send query of size 1334
2025-10-26 19:55:25.937 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:466: {dummy_op} Register async execution for node #199354
2025-10-26 19:55:25.937 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:87: {dummy_op} Finish, output #199356, status: Async
2025-10-26 19:55:25.938 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] grpc_service.cpp:353: {dummy_op} TServiceProxyActor::OnExecuteGraph
2025-10-26 19:55:25.943 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] graph_execution_events_actor.cpp:38: {dummy_op} OnEvent START
2025-10-26 19:55:25.943 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] graph_execution_events_actor.cpp:77: {dummy_op} OnStart
2025-10-26 19:55:25.943 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] actor_helpers.h:147: OnSync(): delayed messages 0
2025-10-26 19:55:25.944 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] grpc_service.cpp:393: {dummy_op} FinishBootstrap
2025-10-26 19:55:25.944 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] result_actor_base.h:117: {dummy_op} _AllResultsBytesLimit = 100000
2025-10-26 19:55:25.944 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] result_actor_base.h:118: {dummy_op} _RowsLimitPerWrite = 1000
2025-10-26 19:55:25.944 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] task_controller_impl.h:78: {dummy_op} _AllResultsBytesLimit = 100000
2025-10-26 19:55:25.945 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] task_controller_impl.h:81: {dummy_op} _RowsLimitPerWrite = 1000
2025-10-26 19:55:25.945 DEBUG dqrun(pid=2000036, tid=0x00007FAC2A64A640) [DQ] interconnect_helpers.cpp:40: 2025-10-26T19:55:25.945377Z :ActorLib INFO: SessionId: dummy_op 2025-10-26 19:55:25.945 INFO  dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] executer_actor.cpp:157: {dummy_op} Trying to allocate 2 workers [EnableComputeActor=1]

2025-10-26 19:55:25.946 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] local_worker_manager.cpp:316: {dummy_op} TLocalWorkerManager::TEvAllocateWorkersRequest 297074297929728
2025-10-26 19:55:25.946 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] local_worker_manager.cpp:376: {dummy_op} Create compute actor: async
2025-10-26 19:55:25.947 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] local_worker_manager.cpp:376: {dummy_op} Create compute actor: async
2025-10-26 19:55:25.948 DEBUG dqrun(pid=2000036, tid=0x00007FAC2800A640) [DQ] resource_allocator.cpp:141: {dummy_op/[1:7565621508458728757:8199]} TEvAllocateWorkersResponse 1
2025-10-26 19:55:25.948 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Build task: 1
2025-10-26 19:55:25.949 DEBUG dqrun(pid=2000036, tid=0x00007FAC2A64A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Build task: 2
2025-10-26 19:55:25.949 DEBUG dqrun(pid=2000036, tid=0x00007FAC2800A640) [DQ] interconnect_helpers.cpp:40: 2025-10-26T19:55:25.949417Z :ActorLib INFO: SessionId: dummy_op 2025-10-26 19:55:25.949 INFO  dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] executer_actor.cpp:412: {dummy_op} 2 workers allocated

2025-10-26 19:55:25.950 DEBUG dqrun(pid=2000036, tid=0x00007FAC2A64A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} task: 2, program size: 6, llvm: ``.
2025-10-26 19:55:25.950 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} task: 1, program size: 34, llvm: ``.
2025-10-26 19:55:25.991 DEBUG dqrun(pid=2000036, tid=0x00007FAC2A64A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Build task: 2 takes 41709 us
2025-10-26 19:55:25.991 DEBUG dqrun(pid=2000036, tid=0x00007FAC2A64A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Prepare task: 2
2025-10-26 19:55:25.991 DEBUG dqrun(pid=2000036, tid=0x00007FAC2A64A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Prepare task: 2, takes 154 us
2025-10-26 19:55:25.992 DEBUG dqrun(pid=2000036, tid=0x00007FAC2A64A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:55:26.280 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Build task: 1 takes 331317 us
2025-10-26 19:55:26.280 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Prepare task: 1
2025-10-26 19:55:26.280 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Prepare task: 1, takes 88 us
2025-10-26 19:55:26.280 INFO  dqrun(pid=2000036, tid=0x00007FAC2AE59640) [generic] yql_generic_read_actor.cpp:454: Creating read actor with params: kind=CLICKHOUSE, endpoint=host: "172.18.0.2" port: 9000, database=constant_NATIVE, use_tls=0, protocol=NATIVE
2025-10-26 19:55:26.281 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [generic] yql_generic_read_actor.cpp:83: Start splits listing
2025-10-26 19:55:26.282 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:55:26.283 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [generic] yql_generic_read_actor.cpp:147: Handle :: EvListSplitsFinished :: last message was reached, start data reading
2025-10-26 19:55:26.283 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [generic] yql_generic_read_actor.cpp:167: Start splits reading
2025-10-26 19:55:26.289 DEBUG dqrun(pid=2000036, tid=0x00007FAC2A64A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:55:26.289 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:55:26.289 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [generic] yql_generic_read_actor.cpp:240: Handle :: EvReadSplitsFinished :: last message was reached, finish data reading
2025-10-26 19:55:26.290 INFO  dqrun(pid=2000036, tid=0x00007FAC2AE59640) [generic] yql_generic_read_actor.cpp:335: GetAsyncInputData :: data reading finished
2025-10-26 19:55:26.290 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:55:26.290 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, task1, execution finished, finish consumers
2025-10-26 19:55:26.290 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} channelId: 1. Finish request
2025-10-26 19:55:26.290 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:55:26.290 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:55:26.290 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, task1, execution finished, finish consumers
2025-10-26 19:55:26.290 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} channelId: 1. Finish request
2025-10-26 19:55:26.290 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:55:26.290 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, task1, execution finished, finish consumers
2025-10-26 19:55:26.291 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} channelId: 1. Finish request
2025-10-26 19:55:26.291 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:55:26.291 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:55:26.291 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, task2, execution finished, finish consumers
2025-10-26 19:55:26.291 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, task1, execution finished, finish consumers
2025-10-26 19:55:26.291 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] tasks_runner_local.cpp:267: {TODO/1} channelId: 1. Finish request
2025-10-26 19:55:26.291 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] tasks_runner_local.cpp:267: {TODO/2} channelId: 2. Finish request
2025-10-26 19:55:26.291 INFO  dqrun(pid=2000036, tid=0x00007FAC2AE59640) [generic] yql_generic_read_actor.cpp:400: PassAway :: final ingress stats: bytes 3, rows 3, chunks 1
2025-10-26 19:55:26.291 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:55:26.292 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, task2, execution finished, finish consumers
2025-10-26 19:55:26.292 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] tasks_runner_local.cpp:267: {TODO/2} channelId: 2. Finish request
2025-10-26 19:55:26.294 DEBUG dqrun(pid=2000036, tid=0x00007FAC2B668640) [DQ] grpc_service.cpp:211: {dummy_op} TServiceProxyActor::OnReturnResult 93
2025-10-26 19:55:26.294 DEBUG dqrun(pid=2000036, tid=0x00007FAC26CEA640) [DQ] resource_allocator.cpp:228: {dummy_op/[1:7565621508458728757:8199]} TEvFreeWorkersNotify 297074297929728
2025-10-26 19:55:26.295 DEBUG dqrun(pid=2000036, tid=0x00007FAC26CEA640) [DQ] resource_allocator.cpp:228: {dummy_op/[1:7565621508458728757:8199]} TEvFreeWorkersNotify 297074297929728
2025-10-26 19:55:26.295 DEBUG dqrun(pid=2000036, tid=0x00007FAC2A64A640) [DQ] local_worker_manager.cpp:416: TEvFreeWorkersNotify 297074297929728
2025-10-26 19:55:26.295 DEBUG dqrun(pid=2000036, tid=0x00007FAC2A64A640) [DQ] local_worker_manager.cpp:440: Free Group 297074297929728
2025-10-26 19:55:26.295 DEBUG dqrun(pid=2000036, tid=0x00007FAC2A64A640) [DQ] local_worker_manager.cpp:416: TEvFreeWorkersNotify 297074297929728
2025-10-26 19:55:26.295 DEBUG dqrun(pid=2000036, tid=0x00007FAC2A64A640) [DQ] local_worker_manager.cpp:440: Free Group 297074297929728
2025-10-26 19:55:26.297 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] graph_execution_events_actor.cpp:38: {dummy_op} OnEvent SUCCESS
2025-10-26 19:55:26.297 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] graph_execution_events_actor.cpp:120: {dummy_op} OnSuccess
2025-10-26 19:55:26.297 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] actor_helpers.h:147: OnSync(): delayed messages 0
2025-10-26 19:55:26.297 DEBUG dqrun(pid=2000036, tid=0x00007FAC2AE59640) [DQ] grpc_service.cpp:333: {dummy_op} DoPassAway
2025-10-26 19:55:26.299 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:133: {dummy_op} Completed async execution for node #199354
2025-10-26 19:55:26.299 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] yql_dq_exectransformer.cpp:1463: {dummy_op} Execution Pull complete, duration: 0.518642s
2025-10-26 19:55:26.299 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:153: {dummy_op} State is ExecutionComplete after apply async changes for node #199354
2025-10-26 19:55:26.299 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:59: {dummy_op} Begin, root #199356
2025-10-26 19:55:26.299 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:72: {dummy_op} Collect unused nodes for root #199356, status: Ok
2025-10-26 19:55:26.299 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:577: {dummy_op} Node #199355<Commit!> finished execution
2025-10-26 19:55:26.299 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:594: {dummy_op} Node #199355<Commit!> created 0 trackable nodes: 
2025-10-26 19:55:26.299 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:577: {dummy_op} Node #199356<Commit!> finished execution
2025-10-26 19:55:26.299 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:594: {dummy_op} Node #199356<Commit!> created 0 trackable nodes: 
2025-10-26 19:55:26.299 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:87: {dummy_op} Finish, output #199356, status: Ok
2025-10-26 19:55:26.299 INFO  dqrun(pid=2000036, tid=0x00007FAC66A79C00) [core exec] yql_execution.cpp:93: {dummy_op} Creating finalizing transformer, output #199356
{
    "Detailed" = {
        "Operations" = [
            {
                "Id" = 8u;
                "Name" = "DqStage";
                "Streams" = {
                    "Program" = [
                        {
                            "Name" = "DqSourceWideBlockWrap"
                        };
                        {
                            "Name" = "WideFromBlocks"
                        };
                        {
                            "Name" = "NarrowMap"
                        };
                        {
                            "Name" = "FlatMap"
                        }
                    ]
                }
            };
            {
                "Id" = 6u;
                "Name" = "DqStage";
                "Streams" = {
                    "Program" = []
                };
                "DependsOn" = [
                    8u
                ]
            };
            {
                "Id" = 3u;
                "Name" = "ResPull!";
                "DependsOn" = [
                    6u
                ]
            };
            {
                "Id" = 1u;
                "Name" = "Commit!";
                "DependsOn" = [
                    3u
                ]
            }
        ];
        "OperationRoot" = 1u;
        "Providers" = [];
        "OperationStats" = {
            "Commit!" = 1u;
            "DqStage" = 2u;
            "ResPull!" = 1u
        }
    };
    "Basic" = {
        "nodes" = [
            {
                "id" = 8u;
                "level" = 1u;
                "name" = "DqStage #8";
                "type" = "op"
            };
            {
                "id" = 6u;
                "level" = 2u;
                "name" = "DqStage #6";
                "type" = "op"
            };
            {
                "id" = 3u;
                "level" = 3u;
                "name" = "ResPull!";
                "type" = "op"
            };
            {
                "id" = 1u;
                "level" = 4u;
                "name" = "Commit!";
                "type" = "op"
            }
        ];
        "links" = [
            {
                "source" = 8u;
                "target" = 6u
            };
            {
                "source" = 6u;
                "target" = 3u
            };
            {
                "source" = 3u;
                "target" = 1u
            }
        ]
    }
}Getting results...

Done
2025-10-26 19:55:26.711 DEBUG dqrun(pid=2000036, tid=0x00007FAC66A79C00) [DQ] yql_dq_provider.cpp:126: {dummy_op} CloseSession dummy_op
2025-10-26 19:55:26.713 DEBUG dqrun(pid=2000036, tid=0x00007FAC083B9640) [DQ] yql_dq_gateway.cpp:412: {dummy_op} Async close session OK
