2025-10-26 19:57:13.425 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [default] dqrun.cpp:756: dqrun ABI version: 2.38.0
2025-10-26 19:57:13.507 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [default] storage.cpp:177: FileStorage initialized in "/home/runner/.ya/build/build_root/70hy/0003ad/r3tmp/tmpNkanjw/", temporary dir: "/home/runner/.ya/build/build_root/70hy/0003ad/r3tmp/tmpNkanjw/2001147", files: 0, total size: 0
2025-10-26 19:57:13.511 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [YT] init.cpp:24: Entering YT API init
2025-10-26 19:57:13.511 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [YT] init.cpp:40: Using YT global prefix: //
2025-10-26 19:57:13.534 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [generic] client.cpp:34: Connector endpoint: grpc://localhost:32779
2025-10-26 19:57:13.551 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:120: Initializing local services
2025-10-26 19:57:13.551 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:147: Initializing node table
2025-10-26 19:57:13.551 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:177: IC Handshake set to 5.000000s
2025-10-26 19:57:13.551 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:178: IC DeadPeer set to 30.000000s
2025-10-26 19:57:13.551 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:179: IC CloseOnIdle set to 300.000000s
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:181: IC SendBufferDieLimitInMB set to 512
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:182: IC TotalInflightAmountOfData set to 0
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:183: IC MergePerPeerCounters set to 0
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:184: IC MergePerDataCenterCounters set to 0
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:185: IC TCPSocketBufferSize set to 16777216
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:187: IC PingPeriod set to 3.000000s
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:188: IC ForceConfirmPeriod set to 1.000000s
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:189: IC LostConnection set to 0.000000s
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:190: IC BatchPeriod set to 0.000000s
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:192: IC MessagePendingTimeout set to 5.000000s
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:194: IC MessagePendingSize set to 18446744073709551615
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:195: IC MaxSerializedEventSize set to 67108000
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:196: IC EnableExternalDataChannel set to 0
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:201: Initializing proxy actors
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:212: Start listener
2025-10-26 19:57:13.552 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:215: Start listener ::1:31337 socket: 11
2025-10-26 19:57:13.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] interconnect_helpers.cpp:228: Actor initialization complete
2025-10-26 19:57:13.588 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [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:57:13.594 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] service_node.cpp:33: Start GRPC Listener
Parse SQL...
Compile program...
2025-10-26 19:57:16.297 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [generic] yql_generic_settings.cpp:38: GenericConfiguration::AddCluster: name = clickhouse_integration_test, kind = CLICKHOUSE, database name = pushdown_HTTP, database id = , endpoint = { host: "172.18.0.2" port: 8123 }, use tls = 0, protocol = HTTP
Run program...
2025-10-26 19:57:16.300 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] yql_dq_provider.cpp:109: OpenSession dummy_op
2025-10-26 19:57:16.301 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] yql_dq_gateway.cpp:565: {dummy_op} OpenSession
2025-10-26 19:57:16.301 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] yql_dq_provider.cpp:112: OpenSession dummy_op complete in 0
2025-10-26 19:57:16.304 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core eval] yql_eval_expr.cpp:403: {dummy_op} EvaluateExpression - start
2025-10-26 19:57:16.306 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core eval] yql_eval_expr.cpp:1165: {dummy_op} EvaluateExpression - finish
2025-10-26 19:57:16.308 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [YT] yql_yt_io_discovery.cpp:227: {dummy_op} YtIODiscovery - finish, status: Ok
2025-10-26 19:57:16.310 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [generic] yql_generic_load_meta.cpp:83: {dummy_op} Loading table meta for: `clickhouse_integration_test`.`ta6466541`
2025-10-26 19:57:16.312 DEBUG dqrun(pid=2001147, tid=0x00007F5AC8769640) [DQ] grpc_service.cpp:577: OpenSession for dummy_op runner
2025-10-26 19:57:16.313 INFO  dqrun(pid=2001147, tid=0x00007F5AC4E09640) [DQ] yql_dq_gateway.cpp:593: {dummy_op} OpenSession OK
2025-10-26 19:57:16.326 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [RESULT] yql_result_provider.cpp:1414: {dummy_op} RewriteIO
2025-10-26 19:57:16.331 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4.56ms
2025-10-26 19:57:16.331 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 420us
2025-10-26 19:57:16.332 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.332 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 286us
2025-10-26 19:57:16.332 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.333 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 147us
2025-10-26 19:57:16.333 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.333 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 240us
2025-10-26 19:57:16.333 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 134us
2025-10-26 19:57:16.334 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 476us
2025-10-26 19:57:16.335 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core] yql_co_simple1.cpp:3803: {dummy_op} Canonize Filter
2025-10-26 19:57:16.337 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 348us
2025-10-26 19:57:16.337 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 129us
2025-10-26 19:57:16.337 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.337 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 326us
2025-10-26 19:57:16.338 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 84us
2025-10-26 19:57:16.338 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 282us
2025-10-26 19:57:16.338 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core] yql_co_simple1.cpp:6127: {dummy_op} Expand SqlProject
2025-10-26 19:57:16.339 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 389us
2025-10-26 19:57:16.339 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 59us
2025-10-26 19:57:16.340 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.340 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 230us
2025-10-26 19:57:16.340 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 66us
2025-10-26 19:57:16.340 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 285us
2025-10-26 19:57:16.341 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core] yql_opt_utils.cpp:764: {dummy_op} Enumerate struct literal for FlattenMembers
2025-10-26 19:57:16.341 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 211us
2025-10-26 19:57:16.341 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 99us
2025-10-26 19:57:16.341 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 3us
2025-10-26 19:57:16.342 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 161us
2025-10-26 19:57:16.342 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 104us
2025-10-26 19:57:16.342 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 231us
2025-10-26 19:57:16.342 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core] yql_co_simple1.cpp:2016: {dummy_op} FlatMap with single arg AsList
2025-10-26 19:57:16.343 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 172us
2025-10-26 19:57:16.343 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 61us
2025-10-26 19:57:16.343 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.343 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 150us
2025-10-26 19:57:16.343 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 87us
2025-10-26 19:57:16.344 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 252us
2025-10-26 19:57:16.344 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core] yql_co_simple1.cpp:2071: {dummy_op} FlatMap to ExtractMembers
2025-10-26 19:57:16.344 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 141us
2025-10-26 19:57:16.345 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 108us
2025-10-26 19:57:16.345 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 73us
2025-10-26 19:57:16.345 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 247us
2025-10-26 19:57:16.347 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core] yql_co_extr_members.cpp:343: {dummy_op} Apply ExtractMembers to FlatMap
2025-10-26 19:57:16.348 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 287us
2025-10-26 19:57:16.348 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 72us
2025-10-26 19:57:16.348 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.348 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 231us
2025-10-26 19:57:16.349 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 87us
2025-10-26 19:57:16.349 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 265us
2025-10-26 19:57:16.351 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] yql_dq_recapture.cpp:102: {dummy_op} DqsRecapture
2025-10-26 19:57:16.352 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [generic] yql_generic_datasource_type_ann.cpp:135: {dummy_op} struct column order0: _yql_block_length(Scalar<Uint64>), 1: col_int32(Block<Int32>), 2: col_string(Block<String>)
2025-10-26 19:57:16.352 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 442us
2025-10-26 19:57:16.352 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 221us
2025-10-26 19:57:16.352 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 90us
2025-10-26 19:57:16.353 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 299us
2025-10-26 19:57:16.359 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [generic] yql_optimize.cpp:135: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-10-26 19:57:16.359 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [generic] yql_generic_physical_opt.cpp:160: {dummy_op} Push filter lambda: (
(return (lambda '($1) (== (Member $1 '"col_int32") (Int32 '"1"))))
)

2025-10-26 19:57:16.360 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [generic] yql_optimize.cpp:135: {dummy_op} PhysicalOptimizer-PushFilterToDqSourceWrap
2025-10-26 19:57:16.360 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [generic] yql_generic_datasource_type_ann.cpp:135: {dummy_op} struct column order0: _yql_block_length(Scalar<Uint64>), 1: col_int32(Block<Int32>), 2: col_string(Block<String>)
2025-10-26 19:57:16.360 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 568us
2025-10-26 19:57:16.361 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 101us
2025-10-26 19:57:16.361 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.361 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 236us
2025-10-26 19:57:16.361 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 96us
2025-10-26 19:57:16.362 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 330us
2025-10-26 19:57:16.366 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] yql_optimize.cpp:135: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-10-26 19:57:16.367 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 770us
2025-10-26 19:57:16.367 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 201us
2025-10-26 19:57:16.368 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.368 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 142us
2025-10-26 19:57:16.368 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.369 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 279us
2025-10-26 19:57:16.369 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 150us
2025-10-26 19:57:16.369 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 456us
2025-10-26 19:57:16.375 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] yql_optimize.cpp:135: {dummy_op} DqsPhy-BuildFlatmapStage<false>
2025-10-26 19:57:16.376 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 703us
2025-10-26 19:57:16.376 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 106us
2025-10-26 19:57:16.377 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.377 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 151us
2025-10-26 19:57:16.377 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.377 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 265us
2025-10-26 19:57:16.378 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 135us
2025-10-26 19:57:16.378 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 382us
2025-10-26 19:57:16.384 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] yql_optimize.cpp:135: {dummy_op} DqsPhy-BuildSortStage<false>
2025-10-26 19:57:16.386 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 880us
2025-10-26 19:57:16.386 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 170us
2025-10-26 19:57:16.386 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.386 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 222us
2025-10-26 19:57:16.387 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.387 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 241us
2025-10-26 19:57:16.387 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.388 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 129us
2025-10-26 19:57:16.388 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 177us
2025-10-26 19:57:16.388 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 511us
2025-10-26 19:57:16.397 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [RESULT] yql_result_provider.cpp:769: {dummy_op} ResPull
2025-10-26 19:57:16.398 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 328us
2025-10-26 19:57:16.398 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 110us
2025-10-26 19:57:16.398 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.398 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op} Execution of [ConstraintTransformer::DoTransform] took 140us
2025-10-26 19:57:16.399 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op} Execution of [UpdateCompletness] took 184us
2025-10-26 19:57:16.399 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op} Execution of [EliminateCommonSubExpressions] took 541us
2025-10-26 19:57:16.406 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:59: {dummy_op} Begin, root #199580
2025-10-26 19:57:16.406 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:72: {dummy_op} Collect unused nodes for root #199580, status: Ok
2025-10-26 19:57:16.406 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:577: {dummy_op} Node #199038<Configure!> finished execution
2025-10-26 19:57:16.406 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:594: {dummy_op} Node #199038<Configure!> created 0 trackable nodes: 
2025-10-26 19:57:16.406 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:577: {dummy_op} Node #199576<DqCnResult> finished execution
2025-10-26 19:57:16.406 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:594: {dummy_op} Node #199576<DqCnResult> created 0 trackable nodes: 
2025-10-26 19:57:16.406 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] yql_dq_exectransformer.cpp:1247: {dummy_op/#199578/#199593} Executing Pull (UniqueId=199593)
2025-10-26 19:57:16.407 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 38us
2025-10-26 19:57:16.407 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 33us
2025-10-26 19:57:16.408 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 149us
2025-10-26 19:57:16.408 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 363us
2025-10-26 19:57:16.521 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 1.14ms
2025-10-26 19:57:16.521 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 257us
2025-10-26 19:57:16.521 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 6us
2025-10-26 19:57:16.522 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 338us
2025-10-26 19:57:16.522 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 6us
2025-10-26 19:57:16.522 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 128us
2025-10-26 19:57:16.522 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.522 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 88us
2025-10-26 19:57:16.522 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 182us
2025-10-26 19:57:16.523 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 537us
2025-10-26 19:57:16.529 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core] yql_co_flow2.cpp:1012: {dummy_op/#199578/#199593} Drop FromFlow with ToFlow
2025-10-26 19:57:16.529 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core] yql_co_flow2.cpp:1012: {dummy_op/#199578/#199593} Drop FromFlow with ToFlow
2025-10-26 19:57:16.529 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 430us
2025-10-26 19:57:16.530 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 100us
2025-10-26 19:57:16.530 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.530 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 113us
2025-10-26 19:57:16.530 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.530 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 81us
2025-10-26 19:57:16.530 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 148us
2025-10-26 19:57:16.531 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 433us
2025-10-26 19:57:16.535 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 28us
2025-10-26 19:57:16.535 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 32us
2025-10-26 19:57:16.535 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 150us
2025-10-26 19:57:16.536 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 402us
2025-10-26 19:57:16.536 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core peephole] yql_opt_peephole_physical.cpp:2662: {dummy_op/#199578/#199593} Expand OptionalIf
2025-10-26 19:57:16.537 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 889us
2025-10-26 19:57:16.538 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 153us
2025-10-26 19:57:16.538 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.538 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 207us
2025-10-26 19:57:16.538 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.539 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 695us
2025-10-26 19:57:16.540 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 801us
2025-10-26 19:57:16.540 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 102us
2025-10-26 19:57:16.540 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.540 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 157us
2025-10-26 19:57:16.540 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.541 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 506us
2025-10-26 19:57:16.541 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 163us
2025-10-26 19:57:16.542 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 529us
2025-10-26 19:57:16.542 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core peephole] yql_opt_peephole_physical.cpp:2404: {dummy_op/#199578/#199593} FlatMap over If to Filter
2025-10-26 19:57:16.543 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 877us
2025-10-26 19:57:16.544 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 90us
2025-10-26 19:57:16.544 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.544 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 173us
2025-10-26 19:57:16.544 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.544 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 159us
2025-10-26 19:57:16.544 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.545 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 324us
2025-10-26 19:57:16.545 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 158us
2025-10-26 19:57:16.545 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 509us
2025-10-26 19:57:16.546 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core peephole] yql_opt_peephole_physical.cpp:2237: {dummy_op/#199578/#199593} Swap Filter with NarrowMap
2025-10-26 19:57:16.547 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 733us
2025-10-26 19:57:16.547 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 97us
2025-10-26 19:57:16.547 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.548 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 153us
2025-10-26 19:57:16.548 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.548 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 349us
2025-10-26 19:57:16.548 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 170us
2025-10-26 19:57:16.549 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 471us
2025-10-26 19:57:16.549 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core peephole] yql_opt_peephole_physical.cpp:4269: {dummy_op/#199578/#199593} Drop Member over AsStruct
2025-10-26 19:57:16.550 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 685us
2025-10-26 19:57:16.550 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 93us
2025-10-26 19:57:16.551 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.551 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 124us
2025-10-26 19:57:16.551 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-26 19:57:16.551 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 330us
2025-10-26 19:57:16.551 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 154us
2025-10-26 19:57:16.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 396us
2025-10-26 19:57:16.552 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core peephole] yql_opt_peephole_physical.cpp:3137: {dummy_op/#199578/#199593} Fuse Map with NarrowMap
2025-10-26 19:57:16.553 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 754us
2025-10-26 19:57:16.554 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 82us
2025-10-26 19:57:16.554 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 6us
2025-10-26 19:57:16.554 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 125us
2025-10-26 19:57:16.554 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.554 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 306us
2025-10-26 19:57:16.554 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 125us
2025-10-26 19:57:16.555 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 381us
2025-10-26 19:57:16.555 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core peephole] yql_opt_peephole_physical.cpp:4269: {dummy_op/#199578/#199593} Drop Member over AsStruct
2025-10-26 19:57:16.556 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 547us
2025-10-26 19:57:16.556 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 104us
2025-10-26 19:57:16.556 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-26 19:57:16.556 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 135us
2025-10-26 19:57:16.556 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 3us
2025-10-26 19:57:16.557 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 243us
2025-10-26 19:57:16.557 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 131us
2025-10-26 19:57:16.557 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 396us
2025-10-26 19:57:16.558 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core peephole] yql_opt_peephole_physical.cpp:4634: {dummy_op/#199578/#199593} Swap Sort with NarrowMap
2025-10-26 19:57:16.559 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 470us
2025-10-26 19:57:16.559 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 87us
2025-10-26 19:57:16.559 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 3us
2025-10-26 19:57:16.560 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 706us
2025-10-26 19:57:16.560 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 107us
2025-10-26 19:57:16.560 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 318us
2025-10-26 19:57:16.561 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core dq] yql_dq_exectransformer.cpp:376: {dummy_op/#199578/#199593} Setting block status for stage #199523 to Partial
2025-10-26 19:57:16.561 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 212us
2025-10-26 19:57:16.561 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 162us
2025-10-26 19:57:16.562 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 75us
2025-10-26 19:57:16.562 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 327us
2025-10-26 19:57:16.562 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core dq] yql_dq_exectransformer.cpp:376: {dummy_op/#199578/#199593} Setting block status for stage #199535 to None
2025-10-26 19:57:16.563 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 196us
2025-10-26 19:57:16.563 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 146us
2025-10-26 19:57:16.563 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 119us
2025-10-26 19:57:16.564 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 409us
2025-10-26 19:57:16.564 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core dq] yql_dq_exectransformer.cpp:376: {dummy_op/#199578/#199593} Setting block status for stage #199567 to None
2025-10-26 19:57:16.565 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] type_ann_expr.cpp:44: {dummy_op/#199578/#199593} Execution of [TypeAnnotationTransformer::DoTransform] took 135us
2025-10-26 19:57:16.565 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_constraint.cpp:3079: {dummy_op/#199578/#199593} Execution of [ConstraintTransformer::DoTransform] took 101us
2025-10-26 19:57:16.565 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:598: {dummy_op/#199578/#199593} Execution of [UpdateCompletness] took 117us
2025-10-26 19:57:16.566 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [perf] yql_expr_csee.cpp:611: {dummy_op/#199578/#199593} Execution of [EliminateCommonSubExpressionsForSubGraph] took 412us
2025-10-26 19:57:16.567 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] execution_planner.cpp:152: {dummy_op/#199578/#199593} Execution Plan (
(let $1 '"clickhouse_integration_test")
(let $2 (DataSource '"generic" $1))
(let $3 (Int32 '1))
(let $4 (GenSourceSettings $1 '"ta6466541" (SecureParam '"cluster:default_clickhouse_integration_test") '('"col_int32" '"col_string") (lambda '($12) (== (Member $12 '"col_int32") $3))))
(let $5 '('('"_logical_id" '199523) '('"_id" '"98e06886-fa1db58-6efae7e1-e19c965f") '('"_block_status" '"Partial")))
(let $6 (DqPhyStage '((DqSource $2 $4)) (lambda '($13) (FromFlow (NarrowMap (WideSort (WideFilter (WideFromBlocks (DqSourceWideBlockWrap $13 $2 (StructType '('"col_int32" (DataType 'Int32)) '('"col_string" (DataType 'String))))) (lambda '($14 $15) (== $14 $3))) '('('1 (Bool 'true)))) (lambda '($16 $17) (AsStruct '('"col_string" $17)))))) $5))
(let $7 '('"_block_status" '"None"))
(let $8 '('('"_logical_id" '199535) '('"_id" '"8f618500-8a76421f-619c0626-94f0f0c2") $7))
(let $9 (DqPhyStage '((DqCnMerge (TDqOutput $6 '"0") '('('"col_string" '"Asc")))) (lambda '($18) $18) $8))
(let $10 '('('"_logical_id" '199567) '('"_id" '"204991a9-f082a54e-c8166bea-df3073bc") $7))
(let $11 (DqPhyStage '((DqCnUnionAll (TDqOutput $9 '"0"))) (lambda '($19) $19) $10))
(return (DqCnResult (TDqOutput $11 '"0") '()))
)

2025-10-26 19:57:16.568 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [generic] yql_generic_dq_integration.cpp:126: {dummy_op/#199578/#199593} Filling source settings: cluster: clickhouse_integration_test, table: ta6466541, endpoint: host: "172.18.0.2" port: 8123
2025-10-26 19:57:16.577 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] yql_dq_gateway.cpp:382: {dummy_op} Send query of size 2026
2025-10-26 19:57:16.579 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:466: {dummy_op} Register async execution for node #199578
2025-10-26 19:57:16.579 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:87: {dummy_op} Finish, output #199580, status: Async
2025-10-26 19:57:16.579 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] grpc_service.cpp:353: {dummy_op} TServiceProxyActor::OnExecuteGraph
2025-10-26 19:57:16.585 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] graph_execution_events_actor.cpp:38: {dummy_op} OnEvent START
2025-10-26 19:57:16.585 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] graph_execution_events_actor.cpp:77: {dummy_op} OnStart
2025-10-26 19:57:16.585 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] actor_helpers.h:147: OnSync(): delayed messages 0
2025-10-26 19:57:16.586 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] grpc_service.cpp:393: {dummy_op} FinishBootstrap
2025-10-26 19:57:16.586 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] result_actor_base.h:117: {dummy_op} _AllResultsBytesLimit = 100000
2025-10-26 19:57:16.586 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] result_actor_base.h:118: {dummy_op} _RowsLimitPerWrite = 1000
2025-10-26 19:57:16.587 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] task_controller_impl.h:78: {dummy_op} _AllResultsBytesLimit = 100000
2025-10-26 19:57:16.587 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] task_controller_impl.h:81: {dummy_op} _RowsLimitPerWrite = 1000
2025-10-26 19:57:16.587 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] interconnect_helpers.cpp:40: 2025-10-26T19:57:16.587704Z :ActorLib INFO: SessionId: dummy_op 2025-10-26 19:57:16.587 INFO  dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] executer_actor.cpp:157: {dummy_op} Trying to allocate 3 workers [EnableComputeActor=1]

2025-10-26 19:57:16.589 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] local_worker_manager.cpp:316: {dummy_op} TLocalWorkerManager::TEvAllocateWorkersRequest 558083755474944
2025-10-26 19:57:16.589 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] local_worker_manager.cpp:376: {dummy_op} Create compute actor: async
2025-10-26 19:57:16.590 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] local_worker_manager.cpp:376: {dummy_op} Create compute actor: async
2025-10-26 19:57:16.591 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] local_worker_manager.cpp:376: {dummy_op} Create compute actor: async
2025-10-26 19:57:16.591 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Build task: 1
2025-10-26 19:57:16.593 DEBUG dqrun(pid=2001147, tid=0x00007F5AE1909640) [DQ] resource_allocator.cpp:141: {dummy_op/[1:7565621985152793447:8203]} TEvAllocateWorkersResponse 1
2025-10-26 19:57:16.594 DEBUG dqrun(pid=2001147, tid=0x00007F5AE4A5A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Build task: 2
2025-10-26 19:57:16.595 DEBUG dqrun(pid=2001147, tid=0x00007F5AE1909640) [DQ] interconnect_helpers.cpp:40: 2025-10-26T19:57:16.595220Z :ActorLib INFO: SessionId: dummy_op 2025-10-26 19:57:16.594 INFO  dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] executer_actor.cpp:412: {dummy_op} 3 workers allocated

2025-10-26 19:57:16.595 DEBUG dqrun(pid=2001147, tid=0x00007F5AE4A5A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} task: 2, program size: 6, llvm: ``.
2025-10-26 19:57:16.596 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] tasks_runner_local.cpp:267: {TODO/1} task: 1, program size: 67, llvm: ``.
2025-10-26 19:57:16.599 DEBUG dqrun(pid=2001147, tid=0x00007F5AE373A640) [DQ] tasks_runner_local.cpp:267: {TODO/3} Build task: 3
2025-10-26 19:57:16.599 DEBUG dqrun(pid=2001147, tid=0x00007F5AE373A640) [DQ] tasks_runner_local.cpp:267: {TODO/3} task: 3, program size: 6, llvm: ``.
2025-10-26 19:57:16.648 DEBUG dqrun(pid=2001147, tid=0x00007F5AE4A5A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Build task: 2 takes 54270 us
2025-10-26 19:57:16.648 DEBUG dqrun(pid=2001147, tid=0x00007F5AE4A5A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Prepare task: 2
2025-10-26 19:57:16.649 DEBUG dqrun(pid=2001147, tid=0x00007F5AE4A5A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Prepare task: 2, takes 164 us
2025-10-26 19:57:16.649 DEBUG dqrun(pid=2001147, tid=0x00007F5AE373A640) [DQ] tasks_runner_local.cpp:267: {TODO/3} Build task: 3 takes 50097 us
2025-10-26 19:57:16.649 DEBUG dqrun(pid=2001147, tid=0x00007F5AE373A640) [DQ] tasks_runner_local.cpp:267: {TODO/3} Prepare task: 3
2025-10-26 19:57:16.649 DEBUG dqrun(pid=2001147, tid=0x00007F5AE4A5A640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:57:16.649 DEBUG dqrun(pid=2001147, tid=0x00007F5AE373A640) [DQ] tasks_runner_local.cpp:267: {TODO/3} Run task: 3, Prepare task: 3, takes 50 us
2025-10-26 19:57:16.649 DEBUG dqrun(pid=2001147, tid=0x00007F5AE373A640) [DQ] tasks_runner_local.cpp:267: {TODO/3} Run task: 3, Run task: 3
2025-10-26 19:57:17.250 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Build task: 1 takes 658629 us
2025-10-26 19:57:17.250 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Prepare task: 1
2025-10-26 19:57:17.250 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Prepare task: 1, takes 74 us
2025-10-26 19:57:17.250 INFO  dqrun(pid=2001147, tid=0x00007F5AE6589640) [generic] yql_generic_read_actor.cpp:454: Creating read actor with params: kind=CLICKHOUSE, endpoint=host: "172.18.0.2" port: 8123, database=pushdown_HTTP, use_tls=0, protocol=HTTP
2025-10-26 19:57:17.251 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [generic] yql_generic_read_actor.cpp:83: Start splits listing
2025-10-26 19:57:17.252 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:57:17.254 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [generic] yql_generic_read_actor.cpp:147: Handle :: EvListSplitsFinished :: last message was reached, start data reading
2025-10-26 19:57:17.254 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [generic] yql_generic_read_actor.cpp:167: Start splits reading
2025-10-26 19:57:17.260 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:57:17.260 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:57:17.260 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] tasks_runner_local.cpp:267: {TODO/3} Run task: 3, Run task: 3
2025-10-26 19:57:17.266 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:57:17.266 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:57:17.267 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [generic] yql_generic_read_actor.cpp:240: Handle :: EvReadSplitsFinished :: last message was reached, finish data reading
2025-10-26 19:57:17.267 INFO  dqrun(pid=2001147, tid=0x00007F5AE80B8640) [generic] yql_generic_read_actor.cpp:335: GetAsyncInputData :: data reading finished
2025-10-26 19:57:17.267 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:57:17.267 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, task1, execution finished, finish consumers
2025-10-26 19:57:17.267 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] tasks_runner_local.cpp:267: {TODO/1} channelId: 1. Finish request
2025-10-26 19:57:17.268 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:57:17.268 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, task2, execution finished, finish consumers
2025-10-26 19:57:17.268 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} channelId: 2. Finish request
2025-10-26 19:57:17.268 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, Run task: 1
2025-10-26 19:57:17.268 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1, task1, execution finished, finish consumers
2025-10-26 19:57:17.268 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] tasks_runner_local.cpp:267: {TODO/1} channelId: 1. Finish request
2025-10-26 19:57:17.269 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] tasks_runner_local.cpp:267: {TODO/3} Run task: 3, Run task: 3
2025-10-26 19:57:17.269 INFO  dqrun(pid=2001147, tid=0x00007F5AE80B8640) [generic] yql_generic_read_actor.cpp:400: PassAway :: final ingress stats: bytes 7, rows 1, chunks 1
2025-10-26 19:57:17.269 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, Run task: 2
2025-10-26 19:57:17.269 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] tasks_runner_local.cpp:267: {TODO/3} Run task: 3, task3, execution finished, finish consumers
2025-10-26 19:57:17.269 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2, task2, execution finished, finish consumers
2025-10-26 19:57:17.269 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} channelId: 2. Finish request
2025-10-26 19:57:17.270 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] tasks_runner_local.cpp:267: {TODO/3} channelId: 3. Finish request
2025-10-26 19:57:17.270 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [DQ] tasks_runner_local.cpp:267: {TODO/3} Run task: 3, Run task: 3
2025-10-26 19:57:17.271 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [DQ] tasks_runner_local.cpp:267: {TODO/3} Run task: 3, task3, execution finished, finish consumers
2025-10-26 19:57:17.271 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [DQ] tasks_runner_local.cpp:267: {TODO/3} channelId: 3. Finish request
2025-10-26 19:57:17.274 DEBUG dqrun(pid=2001147, tid=0x00007F5AE78A9640) [DQ] grpc_service.cpp:211: {dummy_op} TServiceProxyActor::OnReturnResult 106
2025-10-26 19:57:17.274 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] resource_allocator.cpp:228: {dummy_op/[1:7565621985152793447:8203]} TEvFreeWorkersNotify 558083755474944
2025-10-26 19:57:17.274 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] resource_allocator.cpp:228: {dummy_op/[1:7565621985152793447:8203]} TEvFreeWorkersNotify 558083755474944
2025-10-26 19:57:17.274 DEBUG dqrun(pid=2001147, tid=0x00007F5AE6589640) [DQ] resource_allocator.cpp:228: {dummy_op/[1:7565621985152793447:8203]} TEvFreeWorkersNotify 558083755474944
2025-10-26 19:57:17.274 DEBUG dqrun(pid=2001147, tid=0x00007F5AE373A640) [DQ] local_worker_manager.cpp:416: TEvFreeWorkersNotify 558083755474944
2025-10-26 19:57:17.275 DEBUG dqrun(pid=2001147, tid=0x00007F5AE373A640) [DQ] local_worker_manager.cpp:440: Free Group 558083755474944
2025-10-26 19:57:17.278 DEBUG dqrun(pid=2001147, tid=0x00007F5AE373A640) [DQ] local_worker_manager.cpp:416: TEvFreeWorkersNotify 558083755474944
2025-10-26 19:57:17.278 DEBUG dqrun(pid=2001147, tid=0x00007F5AE373A640) [DQ] local_worker_manager.cpp:440: Free Group 558083755474944
2025-10-26 19:57:17.279 DEBUG dqrun(pid=2001147, tid=0x00007F5AE373A640) [DQ] local_worker_manager.cpp:416: TEvFreeWorkersNotify 558083755474944
2025-10-26 19:57:17.279 DEBUG dqrun(pid=2001147, tid=0x00007F5AE373A640) [DQ] local_worker_manager.cpp:440: Free Group 558083755474944
2025-10-26 19:57:17.279 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] graph_execution_events_actor.cpp:38: {dummy_op} OnEvent SUCCESS
2025-10-26 19:57:17.279 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] graph_execution_events_actor.cpp:120: {dummy_op} OnSuccess
2025-10-26 19:57:17.280 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] actor_helpers.h:147: OnSync(): delayed messages 0
2025-10-26 19:57:17.280 DEBUG dqrun(pid=2001147, tid=0x00007F5AE80B8640) [DQ] grpc_service.cpp:333: {dummy_op} DoPassAway
2025-10-26 19:57:17.282 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:133: {dummy_op} Completed async execution for node #199578
2025-10-26 19:57:17.282 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] yql_dq_exectransformer.cpp:1463: {dummy_op} Execution Pull complete, duration: 0.875572s
2025-10-26 19:57:17.282 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:153: {dummy_op} State is ExecutionComplete after apply async changes for node #199578
2025-10-26 19:57:17.282 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:59: {dummy_op} Begin, root #199580
2025-10-26 19:57:17.282 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:72: {dummy_op} Collect unused nodes for root #199580, status: Ok
2025-10-26 19:57:17.283 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:577: {dummy_op} Node #199579<Commit!> finished execution
2025-10-26 19:57:17.283 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:594: {dummy_op} Node #199579<Commit!> created 0 trackable nodes: 
2025-10-26 19:57:17.283 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:577: {dummy_op} Node #199580<Commit!> finished execution
2025-10-26 19:57:17.283 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:594: {dummy_op} Node #199580<Commit!> created 0 trackable nodes: 
2025-10-26 19:57:17.283 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:87: {dummy_op} Finish, output #199580, status: Ok
2025-10-26 19:57:17.283 INFO  dqrun(pid=2001147, tid=0x00007F5B234C9C00) [core exec] yql_execution.cpp:93: {dummy_op} Creating finalizing transformer, output #199580
{
    "Detailed" = {
        "Operations" = [
            {
                "Id" = 12u;
                "Name" = "DqStage";
                "Streams" = {
                    "Program" = [
                        {
                            "Name" = "DqSourceWideBlockWrap"
                        };
                        {
                            "Name" = "WideFromBlocks"
                        };
                        {
                            "Name" = "NarrowMap"
                        };
                        {
                            "Name" = "FlatMap"
                        };
                        {
                            "Name" = "Sort"
                        }
                    ]
                }
            };
            {
                "Id" = 10u;
                "Name" = "DqStage";
                "Streams" = {
                    "Program" = []
                };
                "DependsOn" = [
                    12u
                ]
            };
            {
                "Id" = 8u;
                "Name" = "DqStage";
                "Streams" = {
                    "Program" = []
                };
                "DependsOn" = [
                    10u
                ]
            };
            {
                "Id" = 3u;
                "Name" = "ResPull!";
                "DependsOn" = [
                    8u
                ]
            };
            {
                "Id" = 1u;
                "Name" = "Commit!";
                "DependsOn" = [
                    3u
                ]
            }
        ];
        "OperationRoot" = 1u;
        "Providers" = [];
        "OperationStats" = {
            "Commit!" = 1u;
            "DqStage" = 3u;
            "ResPull!" = 1u
        }
    };
    "Basic" = {
        "nodes" = [
            {
                "id" = 12u;
                "level" = 1u;
                "name" = "DqStage #12";
                "type" = "op"
            };
            {
                "id" = 10u;
                "level" = 2u;
                "name" = "DqStage #10";
                "type" = "op"
            };
            {
                "id" = 8u;
                "level" = 3u;
                "name" = "DqStage #8";
                "type" = "op"
            };
            {
                "id" = 3u;
                "level" = 4u;
                "name" = "ResPull!";
                "type" = "op"
            };
            {
                "id" = 1u;
                "level" = 5u;
                "name" = "Commit!";
                "type" = "op"
            }
        ];
        "links" = [
            {
                "source" = 12u;
                "target" = 10u
            };
            {
                "source" = 10u;
                "target" = 8u
            };
            {
                "source" = 8u;
                "target" = 3u
            };
            {
                "source" = 3u;
                "target" = 1u
            }
        ]
    }
}Getting results...

Done
2025-10-26 19:57:17.301 DEBUG dqrun(pid=2001147, tid=0x00007F5B234C9C00) [DQ] yql_dq_provider.cpp:126: {dummy_op} CloseSession dummy_op
2025-10-26 19:57:17.305 DEBUG dqrun(pid=2001147, tid=0x00007F5AC4E09640) [DQ] yql_dq_gateway.cpp:412: {dummy_op} Async close session OK
