2025-10-26 19:56:28.405 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [default] dqrun.cpp:756: dqrun ABI version: 2.38.0
2025-10-26 19:56:28.491 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [default] storage.cpp:177: FileStorage initialized in "/home/runner/.ya/build/build_root/70hy/0003ad/r3tmp/tmpL2M6VA/", temporary dir: "/home/runner/.ya/build/build_root/70hy/0003ad/r3tmp/tmpL2M6VA/2000707", files: 0, total size: 0
2025-10-26 19:56:28.495 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [YT] init.cpp:24: Entering YT API init
2025-10-26 19:56:28.495 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [YT] init.cpp:40: Using YT global prefix: //
2025-10-26 19:56:28.516 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [generic] client.cpp:34: Connector endpoint: grpc://localhost:32779
2025-10-26 19:56:28.537 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:120: Initializing local services
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:147: Initializing node table
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:177: IC Handshake set to 5.000000s
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:178: IC DeadPeer set to 30.000000s
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:179: IC CloseOnIdle set to 300.000000s
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:181: IC SendBufferDieLimitInMB set to 512
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:182: IC TotalInflightAmountOfData set to 0
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:183: IC MergePerPeerCounters set to 0
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:184: IC MergePerDataCenterCounters set to 0
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:185: IC TCPSocketBufferSize set to 16777216
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:187: IC PingPeriod set to 3.000000s
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:188: IC ForceConfirmPeriod set to 1.000000s
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:189: IC LostConnection set to 0.000000s
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:190: IC BatchPeriod set to 0.000000s
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:192: IC MessagePendingTimeout set to 5.000000s
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:194: IC MessagePendingSize set to 18446744073709551615
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:195: IC MaxSerializedEventSize set to 67108000
2025-10-26 19:56:28.538 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:196: IC EnableExternalDataChannel set to 0
2025-10-26 19:56:28.539 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:201: Initializing proxy actors
2025-10-26 19:56:28.539 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:212: Start listener
2025-10-26 19:56:28.539 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:215: Start listener ::1:31337 socket: 11
2025-10-26 19:56:28.539 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] interconnect_helpers.cpp:228: Actor initialization complete
2025-10-26 19:56:28.577 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [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:56:28.583 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] service_node.cpp:33: Start GRPC Listener
Parse SQL...
Compile program...
2025-10-26 19:56:31.644 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [generic] yql_generic_settings.cpp:38: GenericConfiguration::AddCluster: name = clickhouse_integration_test, kind = CLICKHOUSE, database name = count_HTTP, database id = , endpoint = { host: "172.18.0.2" port: 8123 }, use tls = 0, protocol = HTTP
Run program...
2025-10-26 19:56:31.647 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_dq_provider.cpp:109: OpenSession dummy_op
2025-10-26 19:56:31.647 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_dq_gateway.cpp:565: {dummy_op} OpenSession
2025-10-26 19:56:31.647 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_dq_provider.cpp:112: OpenSession dummy_op complete in 0
2025-10-26 19:56:31.652 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core eval] yql_eval_expr.cpp:403: {dummy_op} EvaluateExpression - start
2025-10-26 19:56:31.653 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core eval] yql_eval_expr.cpp:1165: {dummy_op} EvaluateExpression - finish
2025-10-26 19:56:31.654 DEBUG dqrun(pid=2000707, tid=0x00007F09BE819640) [DQ] grpc_service.cpp:577: OpenSession for dummy_op runner
2025-10-26 19:56:31.656 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [YT] yql_yt_io_discovery.cpp:227: {dummy_op} YtIODiscovery - finish, status: Ok
2025-10-26 19:56:31.656 INFO  dqrun(pid=2000707, tid=0x00007F09BAEB9640) [DQ] yql_dq_gateway.cpp:593: {dummy_op} OpenSession OK
2025-10-26 19:56:31.657 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [generic] yql_generic_load_meta.cpp:83: {dummy_op} Loading table meta for: `clickhouse_integration_test`.`t5bb903ea`
2025-10-26 19:56:31.675 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [RESULT] yql_result_provider.cpp:1414: {dummy_op} RewriteIO
2025-10-26 19:56:31.680 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] type_ann_expr.cpp:134: {dummy_op} Repeated callable stats:
AssumeColumnOrder=1
GenReadTable!=1
SqlProject=1
AssumeColumnOrderPartial=1
ResWrite!=1
SqlProjectItem=1
RemovePrefixMembers=1
ListItemType=1
PersistableRepr=2
TypeOf=2
AggregationTraits=6

2025-10-26 19:56:31.680 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5.48ms
2025-10-26 19:56:31.681 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 173us
2025-10-26 19:56:31.681 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 544us
2025-10-26 19:56:31.682 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 6us
2025-10-26 19:56:31.682 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 429us
2025-10-26 19:56:31.683 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 146us
2025-10-26 19:56:31.683 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 599us
2025-10-26 19:56:31.685 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_simple1.cpp:5129: {dummy_op} AggrCountInit - 1
2025-10-26 19:56:31.685 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_simple1.cpp:5143: {dummy_op} AggrCountUpdate - Inc
2025-10-26 19:56:31.686 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 567us
2025-10-26 19:56:31.687 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 127us
2025-10-26 19:56:31.687 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.687 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 170us
2025-10-26 19:56:31.687 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 110us
2025-10-26 19:56:31.688 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 353us
2025-10-26 19:56:31.688 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_simple1.cpp:6127: {dummy_op} Expand SqlProject
2025-10-26 19:56:31.689 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 454us
2025-10-26 19:56:31.689 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 112us
2025-10-26 19:56:31.690 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.690 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 229us
2025-10-26 19:56:31.690 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 110us
2025-10-26 19:56:31.691 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 341us
2025-10-26 19:56:31.691 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_opt_utils.cpp:764: {dummy_op} Enumerate struct literal for FlattenMembers
2025-10-26 19:56:31.692 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 300us
2025-10-26 19:56:31.692 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 69us
2025-10-26 19:56:31.692 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.692 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 140us
2025-10-26 19:56:31.693 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 117us
2025-10-26 19:56:31.693 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 316us
2025-10-26 19:56:31.693 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_simple1.cpp:2016: {dummy_op} FlatMap with single arg AsList
2025-10-26 19:56:31.694 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 301us
2025-10-26 19:56:31.694 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 58us
2025-10-26 19:56:31.695 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 6us
2025-10-26 19:56:31.695 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 133us
2025-10-26 19:56:31.695 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 105us
2025-10-26 19:56:31.695 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 322us
2025-10-26 19:56:31.698 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_flow2.cpp:1710: {dummy_op} FieldSubset for AggregationTraits
2025-10-26 19:56:31.699 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 887us
2025-10-26 19:56:31.700 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 159us
2025-10-26 19:56:31.700 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.700 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 241us
2025-10-26 19:56:31.700 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 110us
2025-10-26 19:56:31.701 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 372us
2025-10-26 19:56:31.702 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_flow2.cpp:1873: {dummy_op} AggregateSubsetFieldsAnalyzer
2025-10-26 19:56:31.703 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 227us
2025-10-26 19:56:31.703 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 142us
2025-10-26 19:56:31.703 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 104us
2025-10-26 19:56:31.704 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 299us
2025-10-26 19:56:31.706 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_dq_recapture.cpp:102: {dummy_op} DqsRecapture
2025-10-26 19:56:31.707 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [generic] yql_generic_datasource_type_ann.cpp:135: {dummy_op} struct column order0: _yql_block_length(Scalar<Uint64>), 1: col(Block<Double>)
2025-10-26 19:56:31.707 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 463us
2025-10-26 19:56:31.708 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 194us
2025-10-26 19:56:31.708 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 103us
2025-10-26 19:56:31.708 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 365us
2025-10-26 19:56:31.712 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [generic] yql_optimize.cpp:135: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSourceWrap
2025-10-26 19:56:31.713 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [generic] yql_generic_datasource_type_ann.cpp:135: {dummy_op} struct column order0: _yql_block_length(Scalar<Uint64>)
2025-10-26 19:56:31.713 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 387us
2025-10-26 19:56:31.713 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 160us
2025-10-26 19:56:31.714 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 124us
2025-10-26 19:56:31.714 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 324us
2025-10-26 19:56:31.719 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [generic] yql_optimize.cpp:135: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-10-26 19:56:31.720 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 105us
2025-10-26 19:56:31.720 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 76us
2025-10-26 19:56:31.720 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 117us
2025-10-26 19:56:31.720 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 319us
2025-10-26 19:56:31.724 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_optimize.cpp:135: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-10-26 19:56:31.725 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 711us
2025-10-26 19:56:31.725 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 240us
2025-10-26 19:56:31.726 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.726 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 263us
2025-10-26 19:56:31.726 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 152us
2025-10-26 19:56:31.727 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 431us
2025-10-26 19:56:31.730 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_optimize.cpp:135: {dummy_op} DqsLogical-RewriteAggregate
2025-10-26 19:56:31.731 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 267us
2025-10-26 19:56:31.731 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 152us
2025-10-26 19:56:31.732 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 103us
2025-10-26 19:56:31.732 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 325us
2025-10-26 19:56:31.732 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_simple1.cpp:1994: {dummy_op} Fuse FlatMap over AsList
2025-10-26 19:56:31.733 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 181us
2025-10-26 19:56:31.733 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 121us
2025-10-26 19:56:31.733 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 103us
2025-10-26 19:56:31.734 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 312us
2025-10-26 19:56:31.734 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_simple1.cpp:4479: {dummy_op} Member over AsStruct
2025-10-26 19:56:31.734 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 164us
2025-10-26 19:56:31.735 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 113us
2025-10-26 19:56:31.735 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 79us
2025-10-26 19:56:31.735 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 252us
2025-10-26 19:56:31.735 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_simple1.cpp:4844: {dummy_op} ToList over Just
2025-10-26 19:56:31.736 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 142us
2025-10-26 19:56:31.736 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 95us
2025-10-26 19:56:31.736 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 96us
2025-10-26 19:56:31.737 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 256us
2025-10-26 19:56:31.741 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_optimize.cpp:135: {dummy_op} DqsPhy-RewriteLengthOfStageOutput<false>
2025-10-26 19:56:31.742 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 1.13ms
2025-10-26 19:56:31.743 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 207us
2025-10-26 19:56:31.743 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.743 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 246us
2025-10-26 19:56:31.744 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.744 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 160us
2025-10-26 19:56:31.744 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 7us
2025-10-26 19:56:31.745 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 281us
2025-10-26 19:56:31.745 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 146us
2025-10-26 19:56:31.745 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 478us
2025-10-26 19:56:31.752 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_optimize.cpp:135: {dummy_op} DqsPhy-BuildAggregationResultStage
2025-10-26 19:56:31.753 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 656us
2025-10-26 19:56:31.753 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 140us
2025-10-26 19:56:31.753 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 7us
2025-10-26 19:56:31.753 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 124us
2025-10-26 19:56:31.754 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.754 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 190us
2025-10-26 19:56:31.754 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 197us
2025-10-26 19:56:31.755 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 549us
2025-10-26 19:56:31.755 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_simple1.cpp:3823: {dummy_op} Canonize Map
2025-10-26 19:56:31.757 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 539us
2025-10-26 19:56:31.757 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 94us
2025-10-26 19:56:31.757 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.758 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 113us
2025-10-26 19:56:31.758 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.758 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 206us
2025-10-26 19:56:31.758 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 163us
2025-10-26 19:56:31.759 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 466us
2025-10-26 19:56:31.769 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [RESULT] yql_result_provider.cpp:769: {dummy_op} ResPull
2025-10-26 19:56:31.770 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 401us
2025-10-26 19:56:31.770 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 146us
2025-10-26 19:56:31.770 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 7us
2025-10-26 19:56:31.771 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 162us
2025-10-26 19:56:31.771 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 198us
2025-10-26 19:56:31.772 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 545us
2025-10-26 19:56:31.780 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:59: {dummy_op} Begin, root #199789
2025-10-26 19:56:31.780 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:72: {dummy_op} Collect unused nodes for root #199789, status: Ok
2025-10-26 19:56:31.780 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:577: {dummy_op} Node #199031<Configure!> finished execution
2025-10-26 19:56:31.780 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:594: {dummy_op} Node #199031<Configure!> created 0 trackable nodes: 
2025-10-26 19:56:31.780 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:577: {dummy_op} Node #199785<DqCnResult> finished execution
2025-10-26 19:56:31.780 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:594: {dummy_op} Node #199785<DqCnResult> created 0 trackable nodes: 
2025-10-26 19:56:31.781 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_dq_exectransformer.cpp:1247: {dummy_op/#199787/#199802} Executing Pull (UniqueId=199802)
2025-10-26 19:56:31.781 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_dq_exectransformer.cpp:1810: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Executing DqPhyPrecompute
2025-10-26 19:56:31.782 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 37us
2025-10-26 19:56:31.782 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 32us
2025-10-26 19:56:31.782 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [UpdateCompletness] took 116us
2025-10-26 19:56:31.783 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [EliminateCommonSubExpressionsForSubGraph] took 281us
2025-10-26 19:56:31.905 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 934us
2025-10-26 19:56:31.905 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 224us
2025-10-26 19:56:31.905 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 6us
2025-10-26 19:56:31.905 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 225us
2025-10-26 19:56:31.905 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 6us
2025-10-26 19:56:31.906 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 148us
2025-10-26 19:56:31.906 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [UpdateCompletness] took 136us
2025-10-26 19:56:31.906 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [EliminateCommonSubExpressionsForSubGraph] took 408us
2025-10-26 19:56:31.908 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] dqs_opt.cpp:65: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} DqPeepholeRewriteLength
2025-10-26 19:56:31.909 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 848us
2025-10-26 19:56:31.909 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 110us
2025-10-26 19:56:31.909 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 6us
2025-10-26 19:56:31.910 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 252us
2025-10-26 19:56:31.910 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:56:31.910 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 262us
2025-10-26 19:56:31.910 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [UpdateCompletness] took 155us
2025-10-26 19:56:31.911 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [EliminateCommonSubExpressionsForSubGraph] took 454us
2025-10-26 19:56:31.915 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_flow1.cpp:657: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Extract single item struct from Condense
2025-10-26 19:56:31.917 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 1.12ms
2025-10-26 19:56:31.917 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 107us
2025-10-26 19:56:31.917 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.917 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 214us
2025-10-26 19:56:31.917 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:56:31.918 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 221us
2025-10-26 19:56:31.918 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:56:31.918 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 147us
2025-10-26 19:56:31.918 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [UpdateCompletness] took 233us
2025-10-26 19:56:31.919 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [EliminateCommonSubExpressionsForSubGraph] took 501us
2025-10-26 19:56:31.921 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_simple1.cpp:4479: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Member over AsStruct
2025-10-26 19:56:31.921 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_simple1.cpp:4479: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Member over AsStruct
2025-10-26 19:56:31.922 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 749us
2025-10-26 19:56:31.923 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 94us
2025-10-26 19:56:31.923 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.923 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 201us
2025-10-26 19:56:31.923 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 6us
2025-10-26 19:56:31.923 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 280us
2025-10-26 19:56:31.924 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [UpdateCompletness] took 160us
2025-10-26 19:56:31.924 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [EliminateCommonSubExpressionsForSubGraph] took 461us
2025-10-26 19:56:31.926 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_simple1.cpp:4479: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Member over AsStruct
2025-10-26 19:56:31.927 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 655us
2025-10-26 19:56:31.927 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 91us
2025-10-26 19:56:31.927 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.927 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 172us
2025-10-26 19:56:31.928 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 6us
2025-10-26 19:56:31.928 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 226us
2025-10-26 19:56:31.928 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [UpdateCompletness] took 158us
2025-10-26 19:56:31.929 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [EliminateCommonSubExpressionsForSubGraph] took 386us
2025-10-26 19:56:31.930 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_simple1.cpp:3823: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Canonize Map
2025-10-26 19:56:31.932 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 722us
2025-10-26 19:56:31.932 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 94us
2025-10-26 19:56:31.932 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.932 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 185us
2025-10-26 19:56:31.932 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.932 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 229us
2025-10-26 19:56:31.933 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [UpdateCompletness] took 156us
2025-10-26 19:56:31.933 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [EliminateCommonSubExpressionsForSubGraph] took 382us
2025-10-26 19:56:31.938 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 30us
2025-10-26 19:56:31.938 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 27us
2025-10-26 19:56:31.938 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [UpdateCompletness] took 130us
2025-10-26 19:56:31.939 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [EliminateCommonSubExpressionsForSubGraph] took 366us
2025-10-26 19:56:31.939 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core peephole] yql_opt_peephole_physical.cpp:2365: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} FlatMap to Map
2025-10-26 19:56:31.940 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 736us
2025-10-26 19:56:31.941 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 132us
2025-10-26 19:56:31.941 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.941 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 176us
2025-10-26 19:56:31.941 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:56:31.941 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 297us
2025-10-26 19:56:31.942 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [UpdateCompletness] took 147us
2025-10-26 19:56:31.942 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [EliminateCommonSubExpressionsForSubGraph] took 398us
2025-10-26 19:56:31.943 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core dq] yql_dq_exectransformer.cpp:376: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Setting block status for stage #199641 to Partial
2025-10-26 19:56:31.943 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 277us
2025-10-26 19:56:31.944 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 175us
2025-10-26 19:56:31.944 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [UpdateCompletness] took 130us
2025-10-26 19:56:31.944 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [EliminateCommonSubExpressionsForSubGraph] took 426us
2025-10-26 19:56:31.945 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core dq] yql_dq_exectransformer.cpp:376: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Setting block status for stage #199666 to None
2025-10-26 19:56:31.945 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [TypeAnnotationTransformer::DoTransform] took 287us
2025-10-26 19:56:31.946 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [ConstraintTransformer::DoTransform] took 128us
2025-10-26 19:56:31.946 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [UpdateCompletness] took 139us
2025-10-26 19:56:31.946 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution of [EliminateCommonSubExpressionsForSubGraph] took 401us
2025-10-26 19:56:31.948 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] execution_planner.cpp:152: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution Plan (
(let $1 '"clickhouse_integration_test")
(let $2 (DataSource '"generic" $1))
(let $3 (GenSourceSettings $1 '"t5bb903ea" (SecureParam '"cluster:default_clickhouse_integration_test") '() (lambda '($10) (Bool '"true"))))
(let $4 (Uint64 '"0"))
(let $5 (Bool 'false))
(let $6 '('('"_logical_id" '199641) '('"_id" '"fa62ced7-bb9b63a7-e3350b97-cfc88ec9") '('"_block_status" '"Partial")))
(let $7 (DqPhyStage '((DqSource $2 $3)) (lambda '($11) (block '(
  (let $12 (Condense (NarrowMap (WideFromBlocks (DqSourceWideBlockWrap $11 $2 (StructType))) (lambda '() (AsStruct))) $4 (lambda '($13 $14) $5) (lambda '($15 $16) (AggrAdd $16 (Uint64 '"1")))))
  (return (FromFlow (Map $12 (lambda '($17) (AsStruct '('"_dq_agg_cnt" $17))))))
))) $6))
(let $8 '('('"_logical_id" '199666) '('"_id" '"4185f6c8-6b62e7b4-a851f175-8d0f13ea") '('"_block_status" '"None")))
(let $9 (DqPhyStage '((DqCnUnionAll (TDqOutput $7 '"0"))) (lambda '($18) (FromFlow (Condense (ToFlow $18) $4 (lambda '($19 $20) $5) (lambda '($21 $22) (AggrAdd $22 (Member $21 '"_dq_agg_cnt")))))) $8))
(return (DqPhyPrecompute (DqCnValue (TDqOutput $9 '"0"))))
)

2025-10-26 19:56:31.949 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [generic] yql_generic_dq_integration.cpp:126: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Filling source settings: cluster: clickhouse_integration_test, table: t5bb903ea, endpoint: host: "172.18.0.2" port: 8123
2025-10-26 19:56:31.959 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_dq_gateway.cpp:382: {dummy_op} Send query of size 1577
2025-10-26 19:56:31.961 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:466: {dummy_op} Register async execution for node #199787
2025-10-26 19:56:31.961 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:87: {dummy_op} Finish, output #199789, status: Async
2025-10-26 19:56:31.962 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] grpc_service.cpp:353: {dummy_op} TServiceProxyActor::OnExecuteGraph
2025-10-26 19:56:31.966 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] graph_execution_events_actor.cpp:38: {dummy_op} OnEvent START
2025-10-26 19:56:31.966 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] graph_execution_events_actor.cpp:77: {dummy_op} OnStart
2025-10-26 19:56:31.967 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] actor_helpers.h:147: OnSync(): delayed messages 0
2025-10-26 19:56:31.967 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] grpc_service.cpp:393: {dummy_op} FinishBootstrap
2025-10-26 19:56:31.967 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] result_actor_base.h:117: {dummy_op} _AllResultsBytesLimit = 64000000
2025-10-26 19:56:31.967 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] result_actor_base.h:118: {dummy_op} _RowsLimitPerWrite = nothing
2025-10-26 19:56:31.968 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] interconnect_helpers.cpp:40: 2025-10-26T19:56:31.968488Z :ActorLib INFO: SessionId: dummy_op 2025-10-26 19:56:31.968 INFO  dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] executer_actor.cpp:157: {dummy_op} Trying to allocate 2 workers [EnableComputeActor=1]

2025-10-26 19:56:31.969 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] local_worker_manager.cpp:316: {dummy_op} TLocalWorkerManager::TEvAllocateWorkersRequest 449867759484928
2025-10-26 19:56:31.969 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] local_worker_manager.cpp:376: {dummy_op} Create compute actor: async
2025-10-26 19:56:31.970 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] local_worker_manager.cpp:376: {dummy_op} Create compute actor: async
2025-10-26 19:56:31.971 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Build task: 1
2025-10-26 19:56:31.971 DEBUG dqrun(pid=2000707, tid=0x00007F09DAB0A640) [DQ] resource_allocator.cpp:141: {dummy_op/[1:7565621790181760671:8203]} TEvAllocateWorkersResponse 1
2025-10-26 19:56:31.973 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Build task: 2
2025-10-26 19:56:31.973 DEBUG dqrun(pid=2000707, tid=0x00007F09DAB0A640) [DQ] interconnect_helpers.cpp:40: 2025-10-26T19:56:31.973088Z :ActorLib INFO: SessionId: dummy_op 2025-10-26 19:56:31.973 INFO  dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] executer_actor.cpp:412: {dummy_op} 2 workers allocated

2025-10-26 19:56:31.974 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} task: 1, program size: 57, llvm: ``.
2025-10-26 19:56:31.974 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} task: 2, program size: 31, llvm: ``.
2025-10-26 19:56:32.200 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Build task: 2 takes 227572 us
2025-10-26 19:56:32.201 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Prepare task: 2
2025-10-26 19:56:32.201 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Prepare task: 2, takes 154 us
2025-10-26 19:56:32.201 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:56:32.435 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Build task: 1 takes 463981 us
2025-10-26 19:56:32.435 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Prepare task: 1
2025-10-26 19:56:32.436 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Prepare task: 1, takes 86 us
2025-10-26 19:56:32.436 INFO  dqrun(pid=2000707, tid=0x00007F09DD959640) [generic] yql_generic_read_actor.cpp:454: Creating read actor with params: kind=CLICKHOUSE, endpoint=host: "172.18.0.2" port: 8123, database=count_HTTP, use_tls=0, protocol=HTTP
2025-10-26 19:56:32.436 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [generic] yql_generic_read_actor.cpp:83: Start splits listing
2025-10-26 19:56:32.437 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:56:32.439 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [generic] yql_generic_read_actor.cpp:147: Handle :: EvListSplitsFinished :: last message was reached, start data reading
2025-10-26 19:56:32.439 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [generic] yql_generic_read_actor.cpp:167: Start splits reading
2025-10-26 19:56:32.445 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:56:32.445 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:56:32.448 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:56:32.449 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [generic] yql_generic_read_actor.cpp:240: Handle :: EvReadSplitsFinished :: last message was reached, finish data reading
2025-10-26 19:56:32.449 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:56:32.449 INFO  dqrun(pid=2000707, tid=0x00007F09DE168640) [generic] yql_generic_read_actor.cpp:335: GetAsyncInputData :: data reading finished
2025-10-26 19:56:32.449 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:56:32.449 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, task1, execution finished, finish consumers
2025-10-26 19:56:32.449 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] tasks_runner_local.cpp:267: {TODO/1} channelId: 1. Finish request
2025-10-26 19:56:32.450 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:56:32.450 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:56:32.450 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, task1, execution finished, finish consumers
2025-10-26 19:56:32.450 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, task2, execution finished, finish consumers
2025-10-26 19:56:32.450 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/2} channelId: 2. Finish request
2025-10-26 19:56:32.450 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] tasks_runner_local.cpp:267: {TODO/1} channelId: 1. Finish request
2025-10-26 19:56:32.451 INFO  dqrun(pid=2000707, tid=0x00007F09DE168640) [generic] yql_generic_read_actor.cpp:400: PassAway :: final ingress stats: bytes 4, rows 4, chunks 1
2025-10-26 19:56:32.452 DEBUG dqrun(pid=2000707, tid=0x00007F09DAB0A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:56:32.452 DEBUG dqrun(pid=2000707, tid=0x00007F09DAB0A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, task2, execution finished, finish consumers
2025-10-26 19:56:32.452 DEBUG dqrun(pid=2000707, tid=0x00007F09DAB0A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} channelId: 2. Finish request
2025-10-26 19:56:32.455 DEBUG dqrun(pid=2000707, tid=0x00007F09DAB0A640) [DQ] resource_allocator.cpp:228: {dummy_op/[1:7565621790181760671:8203]} TEvFreeWorkersNotify 449867759484928
2025-10-26 19:56:32.455 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] grpc_service.cpp:211: {dummy_op} TServiceProxyActor::OnReturnResult 93
2025-10-26 19:56:32.455 DEBUG dqrun(pid=2000707, tid=0x00007F09DAB0A640) [DQ] resource_allocator.cpp:228: {dummy_op/[1:7565621790181760671:8203]} TEvFreeWorkersNotify 449867759484928
2025-10-26 19:56:32.455 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] local_worker_manager.cpp:416: TEvFreeWorkersNotify 449867759484928
2025-10-26 19:56:32.455 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] local_worker_manager.cpp:440: Free Group 449867759484928
2025-10-26 19:56:32.455 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] local_worker_manager.cpp:416: TEvFreeWorkersNotify 449867759484928
2025-10-26 19:56:32.455 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] local_worker_manager.cpp:440: Free Group 449867759484928
2025-10-26 19:56:32.457 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] graph_execution_events_actor.cpp:38: {dummy_op} OnEvent SUCCESS
2025-10-26 19:56:32.457 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] graph_execution_events_actor.cpp:120: {dummy_op} OnSuccess
2025-10-26 19:56:32.457 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] actor_helpers.h:147: OnSync(): delayed messages 0
2025-10-26 19:56:32.458 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] grpc_service.cpp:333: {dummy_op} DoPassAway
2025-10-26 19:56:32.459 INFO  dqrun(pid=2000707, tid=0x00007F09BAEB9640) [DQ] yql_dq_exectransformer.cpp:1971: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Execution precomputes complete, duration: 0.678082s
2025-10-26 19:56:32.459 DEBUG dqrun(pid=2000707, tid=0x00007F09BAEB9640) [DQ] yql_dq_exectransformer.cpp:2022: {dummy_op/#199787/#199802/DqPhyPrecompute(#199675)} Finished
2025-10-26 19:56:32.460 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:133: {dummy_op} Completed async execution for node #199787
2025-10-26 19:56:32.460 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:153: {dummy_op} State is ExecutionRequired after apply async changes for node #199787
2025-10-26 19:56:32.460 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:59: {dummy_op} Begin, root #199789
2025-10-26 19:56:32.460 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:72: {dummy_op} Collect unused nodes for root #199789, status: Ok
2025-10-26 19:56:32.460 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_dq_exectransformer.cpp:1247: {dummy_op/#199787/#200192} Executing Pull (UniqueId=200192)
2025-10-26 19:56:32.461 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#200192} Execution of [TypeAnnotationTransformer::DoTransform] took 31us
2025-10-26 19:56:32.461 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#200192} Execution of [ConstraintTransformer::DoTransform] took 40us
2025-10-26 19:56:32.461 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#200192} Execution of [UpdateCompletness] took 204us
2025-10-26 19:56:32.462 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#200192} Execution of [EliminateCommonSubExpressionsForSubGraph] took 422us
2025-10-26 19:56:32.462 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] dqs_opt.cpp:159: {dummy_op/#199787/#200192} DqsReplacePrecomputes
2025-10-26 19:56:32.463 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#200192} Execution of [TypeAnnotationTransformer::DoTransform] took 353us
2025-10-26 19:56:32.463 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#200192} Execution of [ConstraintTransformer::DoTransform] took 272us
2025-10-26 19:56:32.463 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#200192} Execution of [UpdateCompletness] took 73us
2025-10-26 19:56:32.464 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#200192} Execution of [EliminateCommonSubExpressionsForSubGraph] took 203us
2025-10-26 19:56:32.466 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#200192} Execution of [TypeAnnotationTransformer::DoTransform] took 354us
2025-10-26 19:56:32.466 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#200192} Execution of [ConstraintTransformer::DoTransform] took 131us
2025-10-26 19:56:32.466 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#200192} Execution of [TypeAnnotationTransformer::DoTransform] took 3us
2025-10-26 19:56:32.466 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#200192} Execution of [ConstraintTransformer::DoTransform] took 57us
2025-10-26 19:56:32.466 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#200192} Execution of [UpdateCompletness] took 59us
2025-10-26 19:56:32.466 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#200192} Execution of [EliminateCommonSubExpressionsForSubGraph] took 172us
2025-10-26 19:56:32.469 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core] yql_co_flow2.cpp:1012: {dummy_op/#199787/#200192} Drop FromFlow with ToFlow
2025-10-26 19:56:32.469 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#200192} Execution of [TypeAnnotationTransformer::DoTransform] took 157us
2025-10-26 19:56:32.469 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#200192} Execution of [ConstraintTransformer::DoTransform] took 76us
2025-10-26 19:56:32.469 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#200192} Execution of [TypeAnnotationTransformer::DoTransform] took 3us
2025-10-26 19:56:32.469 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#200192} Execution of [ConstraintTransformer::DoTransform] took 75us
2025-10-26 19:56:32.470 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#200192} Execution of [UpdateCompletness] took 63us
2025-10-26 19:56:32.470 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#200192} Execution of [EliminateCommonSubExpressionsForSubGraph] took 168us
2025-10-26 19:56:32.472 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#200192} Execution of [TypeAnnotationTransformer::DoTransform] took 17us
2025-10-26 19:56:32.472 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#200192} Execution of [ConstraintTransformer::DoTransform] took 14us
2025-10-26 19:56:32.472 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#200192} Execution of [UpdateCompletness] took 39us
2025-10-26 19:56:32.472 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#200192} Execution of [EliminateCommonSubExpressionsForSubGraph] took 177us
2025-10-26 19:56:32.472 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [core peephole] yql_opt_peephole_physical.cpp:2365: {dummy_op/#199787/#200192} FlatMap to Map
2025-10-26 19:56:32.473 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#200192} Execution of [TypeAnnotationTransformer::DoTransform] took 449us
2025-10-26 19:56:32.473 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#200192} Execution of [ConstraintTransformer::DoTransform] took 278us
2025-10-26 19:56:32.474 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#200192} Execution of [TypeAnnotationTransformer::DoTransform] took 11us
2025-10-26 19:56:32.474 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#200192} Execution of [ConstraintTransformer::DoTransform] took 249us
2025-10-26 19:56:32.474 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#200192} Execution of [UpdateCompletness] took 64us
2025-10-26 19:56:32.474 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#200192} Execution of [EliminateCommonSubExpressionsForSubGraph] took 235us
2025-10-26 19:56:32.475 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core dq] yql_dq_exectransformer.cpp:376: {dummy_op/#199787/#200192} Setting block status for stage #199715 to None
2025-10-26 19:56:32.475 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#200192} Execution of [TypeAnnotationTransformer::DoTransform] took 295us
2025-10-26 19:56:32.475 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#200192} Execution of [ConstraintTransformer::DoTransform] took 147us
2025-10-26 19:56:32.476 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#200192} Execution of [UpdateCompletness] took 79us
2025-10-26 19:56:32.476 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#200192} Execution of [EliminateCommonSubExpressionsForSubGraph] took 224us
2025-10-26 19:56:32.476 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core dq] yql_dq_exectransformer.cpp:376: {dummy_op/#199787/#200192} Setting block status for stage #199776 to None
2025-10-26 19:56:32.477 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199787/#200192} Execution of [TypeAnnotationTransformer::DoTransform] took 120us
2025-10-26 19:56:32.477 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199787/#200192} Execution of [ConstraintTransformer::DoTransform] took 72us
2025-10-26 19:56:32.477 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199787/#200192} Execution of [UpdateCompletness] took 55us
2025-10-26 19:56:32.477 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199787/#200192} Execution of [EliminateCommonSubExpressionsForSubGraph] took 168us
2025-10-26 19:56:32.478 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] execution_planner.cpp:152: {dummy_op/#199787/#200192} Execution Plan (
(let $1 '('"_block_status" '"None"))
(let $2 '('('"_logical_id" '199715) '('"_id" '"3978c09-2531a870-32e7a6d5-8f5b4a42") $1))
(let $3 (DqPhyStage '() (lambda '() (Map (Iterator (AsList (Uint64 '"4"))) (lambda '($6) (AsStruct '('"column0" $6))))) $2))
(let $4 '('('"_logical_id" '199776) '('"_id" '"cd366b5b-b9dab687-5c93c5cd-536b20b4") $1))
(let $5 (DqPhyStage '((DqCnUnionAll (TDqOutput $3 '"0"))) (lambda '($7) $7) $4))
(return (DqCnResult (TDqOutput $5 '"0") '()))
)

2025-10-26 19:56:32.480 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_dq_gateway.cpp:382: {dummy_op} Send query of size 916
2025-10-26 19:56:32.482 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:466: {dummy_op} Register async execution for node #199787
2025-10-26 19:56:32.482 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:87: {dummy_op} Finish, output #199789, status: Async
2025-10-26 19:56:32.483 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] grpc_service.cpp:353: {dummy_op} TServiceProxyActor::OnExecuteGraph
2025-10-26 19:56:32.483 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] graph_execution_events_actor.cpp:38: {dummy_op} OnEvent START
2025-10-26 19:56:32.483 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] graph_execution_events_actor.cpp:77: {dummy_op} OnStart
2025-10-26 19:56:32.483 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] actor_helpers.h:147: OnSync(): delayed messages 0
2025-10-26 19:56:32.484 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] grpc_service.cpp:393: {dummy_op} FinishBootstrap
2025-10-26 19:56:32.484 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] result_actor_base.h:117: {dummy_op} _AllResultsBytesLimit = 100000
2025-10-26 19:56:32.484 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] result_actor_base.h:118: {dummy_op} _RowsLimitPerWrite = 1000
2025-10-26 19:56:32.484 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] task_controller_impl.h:78: {dummy_op} _AllResultsBytesLimit = 100000
2025-10-26 19:56:32.484 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] task_controller_impl.h:81: {dummy_op} _RowsLimitPerWrite = 1000
2025-10-26 19:56:32.485 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] interconnect_helpers.cpp:40: 2025-10-26T19:56:32.484885Z :ActorLib INFO: SessionId: dummy_op 2025-10-26 19:56:32.484 INFO  dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] executer_actor.cpp:157: {dummy_op} Trying to allocate 2 workers [EnableComputeActor=1]

2025-10-26 19:56:32.485 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] local_worker_manager.cpp:316: {dummy_op} TLocalWorkerManager::TEvAllocateWorkersRequest 449867759484929
2025-10-26 19:56:32.485 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] local_worker_manager.cpp:376: {dummy_op} Create compute actor: async
2025-10-26 19:56:32.486 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] local_worker_manager.cpp:376: {dummy_op} Create compute actor: async
2025-10-26 19:56:32.486 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Build task: 1
2025-10-26 19:56:32.486 DEBUG dqrun(pid=2000707, tid=0x00007F09DAB0A640) [DQ] resource_allocator.cpp:141: {dummy_op/[1:7565621794476727980:8196]} TEvAllocateWorkersResponse 1
2025-10-26 19:56:32.486 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} task: 1, program size: 15, llvm: ``.
2025-10-26 19:56:32.487 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Build task: 2
2025-10-26 19:56:32.487 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} task: 2, program size: 6, llvm: ``.
2025-10-26 19:56:32.487 DEBUG dqrun(pid=2000707, tid=0x00007F09DAB0A640) [DQ] interconnect_helpers.cpp:40: 2025-10-26T19:56:32.487463Z :ActorLib INFO: SessionId: dummy_op 2025-10-26 19:56:32.487 INFO  dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] executer_actor.cpp:412: {dummy_op} 2 workers allocated

2025-10-26 19:56:32.512 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Build task: 2 takes 24907 us
2025-10-26 19:56:32.512 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Prepare task: 2
2025-10-26 19:56:32.512 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Prepare task: 2, takes 84 us
2025-10-26 19:56:32.512 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:56:32.680 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Build task: 1 takes 194197 us
2025-10-26 19:56:32.680 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Prepare task: 1
2025-10-26 19:56:32.681 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Prepare task: 1, takes 54 us
2025-10-26 19:56:32.681 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:56:32.681 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, task1, execution finished, finish consumers
2025-10-26 19:56:32.681 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} channelId: 1. Finish request
2025-10-26 19:56:32.681 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:56:32.681 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, task1, execution finished, finish consumers
2025-10-26 19:56:32.681 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/1} channelId: 1. Finish request
2025-10-26 19:56:32.682 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:56:32.682 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:56:32.682 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, task2, execution finished, finish consumers
2025-10-26 19:56:32.682 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, task1, execution finished, finish consumers
2025-10-26 19:56:32.682 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/2} channelId: 2. Finish request
2025-10-26 19:56:32.682 DEBUG dqrun(pid=2000707, tid=0x00007F09DD14A640) [DQ] tasks_runner_local.cpp:267: {TODO/1} channelId: 1. Finish request
2025-10-26 19:56:32.713 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:56:32.713 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, task2, execution finished, finish consumers
2025-10-26 19:56:32.713 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] tasks_runner_local.cpp:267: {TODO/2} channelId: 2. Finish request
2025-10-26 19:56:32.714 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:56:32.714 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, task2, execution finished, finish consumers
2025-10-26 19:56:32.714 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] tasks_runner_local.cpp:267: {TODO/2} channelId: 2. Finish request
2025-10-26 19:56:32.715 DEBUG dqrun(pid=2000707, tid=0x00007F09DE168640) [DQ] grpc_service.cpp:211: {dummy_op} TServiceProxyActor::OnReturnResult 42
2025-10-26 19:56:32.715 DEBUG dqrun(pid=2000707, tid=0x00007F09DAB0A640) [DQ] resource_allocator.cpp:228: {dummy_op/[1:7565621794476727980:8196]} TEvFreeWorkersNotify 449867759484929
2025-10-26 19:56:32.716 DEBUG dqrun(pid=2000707, tid=0x00007F09DAB0A640) [DQ] resource_allocator.cpp:228: {dummy_op/[1:7565621794476727980:8196]} TEvFreeWorkersNotify 449867759484929
2025-10-26 19:56:32.716 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] local_worker_manager.cpp:416: TEvFreeWorkersNotify 449867759484929
2025-10-26 19:56:32.716 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] local_worker_manager.cpp:440: Free Group 449867759484929
2025-10-26 19:56:32.716 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] local_worker_manager.cpp:416: TEvFreeWorkersNotify 449867759484929
2025-10-26 19:56:32.716 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] local_worker_manager.cpp:440: Free Group 449867759484929
2025-10-26 19:56:32.716 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] graph_execution_events_actor.cpp:38: {dummy_op} OnEvent SUCCESS
2025-10-26 19:56:32.716 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] graph_execution_events_actor.cpp:120: {dummy_op} OnSuccess
2025-10-26 19:56:32.716 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] actor_helpers.h:147: OnSync(): delayed messages 0
2025-10-26 19:56:32.717 DEBUG dqrun(pid=2000707, tid=0x00007F09DD959640) [DQ] grpc_service.cpp:333: {dummy_op} DoPassAway
2025-10-26 19:56:32.718 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:133: {dummy_op} Completed async execution for node #199787
2025-10-26 19:56:32.718 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_dq_exectransformer.cpp:1463: {dummy_op} Execution Pull complete, duration: 0.257521s
2025-10-26 19:56:32.718 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:153: {dummy_op} State is ExecutionComplete after apply async changes for node #199787
2025-10-26 19:56:32.718 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:59: {dummy_op} Begin, root #199789
2025-10-26 19:56:32.718 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:72: {dummy_op} Collect unused nodes for root #199789, status: Ok
2025-10-26 19:56:32.718 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:577: {dummy_op} Node #199788<Commit!> finished execution
2025-10-26 19:56:32.718 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:594: {dummy_op} Node #199788<Commit!> created 0 trackable nodes: 
2025-10-26 19:56:32.718 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:577: {dummy_op} Node #199789<Commit!> finished execution
2025-10-26 19:56:32.718 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:594: {dummy_op} Node #199789<Commit!> created 0 trackable nodes: 
2025-10-26 19:56:32.718 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:87: {dummy_op} Finish, output #199789, status: Ok
2025-10-26 19:56:32.718 INFO  dqrun(pid=2000707, tid=0x00007F0A19579C00) [core exec] yql_execution.cpp:93: {dummy_op} Creating finalizing transformer, output #199789
{
    "Detailed" = {
        "Operations" = [
            {
                "Id" = 13u;
                "Name" = "DqStage";
                "Streams" = {
                    "Program" = [
                        {
                            "Name" = "DqSourceWideBlockWrap"
                        };
                        {
                            "Name" = "WideFromBlocks"
                        };
                        {
                            "Name" = "NarrowMap"
                        };
                        {
                            "Name" = "DqPhyLength"
                        }
                    ]
                }
            };
            {
                "Id" = 11u;
                "Name" = "DqStage";
                "Streams" = {
                    "Program" = [
                        {
                            "Name" = "Condense"
                        }
                    ]
                };
                "DependsOn" = [
                    13u
                ]
            };
            {
                "Id" = 8u;
                "Name" = "DqStage";
                "Streams" = {
                    "Program" = [
                        {
                            "Name" = "AsList"
                        };
                        {
                            "Name" = "Iterator"
                        };
                        {
                            "Name" = "FlatMap"
                        }
                    ]
                };
                "DependsOn" = [
                    11u
                ]
            };
            {
                "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" = 4u;
            "ResPull!" = 1u
        }
    };
    "Basic" = {
        "nodes" = [
            {
                "id" = 13u;
                "level" = 1u;
                "name" = "DqStage #13";
                "type" = "op"
            };
            {
                "id" = 11u;
                "level" = 2u;
                "name" = "DqStage #11";
                "type" = "op"
            };
            {
                "id" = 8u;
                "level" = 3u;
                "name" = "DqStage #8";
                "type" = "op"
            };
            {
                "id" = 6u;
                "level" = 4u;
                "name" = "DqStage #6";
                "type" = "op"
            };
            {
                "id" = 3u;
                "level" = 5u;
                "name" = "ResPull!";
                "type" = "op"
            };
            {
                "id" = 1u;
                "level" = 6u;
                "name" = "Commit!";
                "type" = "op"
            }
        ];
        "links" = [
            {
                "source" = 13u;
                "target" = 11u
            };
            {
                "source" = 11u;
                "target" = 8u
            };
            {
                "source" = 8u;
                "target" = 6u
            };
            {
                "source" = 6u;
                "target" = 3u
            };
            {
                "source" = 3u;
                "target" = 1u
            }
        ]
    }
}Getting results...

Done
2025-10-26 19:56:33.655 DEBUG dqrun(pid=2000707, tid=0x00007F0A19579C00) [DQ] yql_dq_provider.cpp:126: {dummy_op} CloseSession dummy_op
2025-10-26 19:56:33.657 DEBUG dqrun(pid=2000707, tid=0x00007F09BAEB9640) [DQ] yql_dq_gateway.cpp:412: {dummy_op} Async close session OK
