dqrun ABI version: 2.44.0
2025-12-06 03:10:50.775 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [default] storage.cpp:189: FileStorage initialized in "/home/runner/.ya/build/build_root/o4ua/005281/r3tmp/tmp4s5Cjb/", temporary dir: "/home/runner/.ya/build/build_root/o4ua/005281/r3tmp/tmp4s5Cjb/3262926", locks dir:"/home/runner/.ya/build/build_root/o4ua/005281/r3tmp/tmp4s5Cjb/locks", files: 0, total size: 0
2025-12-06 03:10:50.783 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [generic] client.cpp:436: Connector[default] endpoint: grpcs://connector.yqv2-dev.cloud.yandex.net:50051
2025-12-06 03:10:50.804 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [http gw] yql_dns_gateway.h:85: Filled DNS resolution table based on provided configuration
2025-12-06 03:10:50.805 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [http gw] yql_dns_gateway.h:126: Started DNS table update
2025-12-06 03:10:50.810 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] interconnect_helpers.cpp:215: Start listener ::1:31337 socket: 18
2025-12-06 03:10:50.837 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [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
Parse SQL...
Compile program...
2025-12-06 03:10:51.045 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [generic] yql_generic_settings.cpp:61: GenericConfiguration::AddCluster: name = ydb_dev, kind = YDB, database name = , database id = etnejle6hb72cdr6aqps, endpoint = {  }, use tls = 1, protocol = NATIVE
Run program...
2025-12-06 03:10:51.049 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_dq_gateway.cpp:605: {dummy_op} OpenSession
2025-12-06 03:10:51.053 INFO  dqrun(pid=3262926, tid=0x00007FC952805640) [DQ] yql_dq_gateway.cpp:633: {dummy_op} OpenSession OK
2025-12-06 03:10:51.062 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [YT] yql_yt_io_discovery.cpp:303: {dummy_op} YtIODiscovery - finish, status: Ok
2025-12-06 03:10:51.063 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_listing_strategy.cpp:1018: {dummy_op} [TLoggingS3ListingStrategy] Going to list request [TS3Lister] TListingRequest{.url=file://./tpc/,.Prefix=h/1/orders/,.Pattern=h/1/orders/*,.PatternType=Wildcard,.Credentials=TS3Credentials{.ServiceAccountAuth=1,.AwsUserPwd=<some token with length0>,.AwsSigV4=<some sig with length0>}} with options TS3ListingOptions{.IsPartitionedDataset=0,.IsConcurrentListing=0}
2025-12-06 03:10:51.066 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_listing_strategy.cpp:236: {dummy_op} [TCompositeS3ListingStrategy] Strategy successfully listed paths. Returning result: 30 objects, 0 path prefixes
2025-12-06 03:10:51.066 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000001s and ended with 30 entries
2025-12-06 03:10:51.066 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_listing_strategy.cpp:1018: {dummy_op} [TLoggingS3ListingStrategy] Going to list request [TS3Lister] TListingRequest{.url=file://./tpc/,.Prefix=h/1/lineitem/,.Pattern=h/1/lineitem/*,.PatternType=Wildcard,.Credentials=TS3Credentials{.ServiceAccountAuth=1,.AwsUserPwd=<some token with length0>,.AwsSigV4=<some sig with length0>}} with options TS3ListingOptions{.IsPartitionedDataset=0,.IsConcurrentListing=0}
2025-12-06 03:10:51.068 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_listing_strategy.cpp:236: {dummy_op} [TCompositeS3ListingStrategy] Strategy successfully listed paths. Returning result: 30 objects, 0 path prefixes
2025-12-06 03:10:51.068 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000000s and ended with 30 entries
2025-12-06 03:10:51.068 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_listing_strategy.cpp:1018: {dummy_op} [TLoggingS3ListingStrategy] Going to list request [TS3Lister] TListingRequest{.url=file://./tpc/,.Prefix=h/1/supplier/,.Pattern=h/1/supplier/*,.PatternType=Wildcard,.Credentials=TS3Credentials{.ServiceAccountAuth=1,.AwsUserPwd=<some token with length0>,.AwsSigV4=<some sig with length0>}} with options TS3ListingOptions{.IsPartitionedDataset=0,.IsConcurrentListing=0}
2025-12-06 03:10:51.070 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_listing_strategy.cpp:236: {dummy_op} [TCompositeS3ListingStrategy] Strategy successfully listed paths. Returning result: 30 objects, 0 path prefixes
2025-12-06 03:10:51.070 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000002s and ended with 30 entries
2025-12-06 03:10:51.070 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_listing_strategy.cpp:1018: {dummy_op} [TLoggingS3ListingStrategy] Going to list request [TS3Lister] TListingRequest{.url=file://./tpc/,.Prefix=h/1/nation/,.Pattern=h/1/nation/*,.PatternType=Wildcard,.Credentials=TS3Credentials{.ServiceAccountAuth=1,.AwsUserPwd=<some token with length0>,.AwsSigV4=<some sig with length0>}} with options TS3ListingOptions{.IsPartitionedDataset=0,.IsConcurrentListing=0}
2025-12-06 03:10:51.072 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_listing_strategy.cpp:236: {dummy_op} [TCompositeS3ListingStrategy] Strategy successfully listed paths. Returning result: 1 objects, 0 path prefixes
2025-12-06 03:10:51.072 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000000s and ended with 1 entries
2025-12-06 03:10:51.072 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/lineitem/* has 30 items with total size 188300741
2025-12-06 03:10:51.073 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/nation/* has 1 items with total size 5031
2025-12-06 03:10:51.073 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/supplier/* has 30 items with total size 825819
2025-12-06 03:10:51.074 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/lineitem/* has 30 items with total size 188300741
2025-12-06 03:10:51.075 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/orders/* has 30 items with total size 54575075
2025-12-06 03:10:51.077 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [YT] yql_yt_io_discovery.cpp:303: {dummy_op} YtIODiscovery - finish, status: Ok
2025-12-06 03:10:51.081 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [RESULT] yql_result_provider.cpp:1415: {dummy_op} RewriteIO
2025-12-06 03:10:51.293 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [RESULT] yql_result_provider.cpp:1154: {dummy_op} Setting result column order: [(s_name->s_name), (numwait->numwait)]
2025-12-06 03:10:51.420 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local
2025-12-06 03:10:51.421 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local
2025-12-06 03:10:51.423 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local
2025-12-06 03:10:51.423 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local
2025-12-06 03:10:51.424 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local
2025-12-06 03:10:51.425 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_dq_recapture.cpp:111: {dummy_op} DqsRecapture
2025-12-06 03:10:51.442 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings
2025-12-06 03:10:51.443 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource
2025-12-06 03:10:51.443 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings
2025-12-06 03:10:51.443 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource
2025-12-06 03:10:51.443 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings
2025-12-06 03:10:51.443 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource
2025-12-06 03:10:51.443 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings
2025-12-06 03:10:51.443 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource
2025-12-06 03:10:51.443 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings
2025-12-06 03:10:51.443 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource
2025-12-06 03:10:51.462 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 121 files with total size 432007407 bytes
2025-12-06 03:10:51.466 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-12-06 03:10:51.481 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 121 files with total size 432007407 bytes
2025-12-06 03:10:51.483 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-12-06 03:10:51.497 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 121 files with total size 432007407 bytes
2025-12-06 03:10:51.499 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-12-06 03:10:51.510 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 91 files with total size 243706666 bytes
2025-12-06 03:10:51.512 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-12-06 03:10:51.519 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 91 files with total size 243706666 bytes
2025-12-06 03:10:51.521 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-12-06 03:10:51.528 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 91 files with total size 243706666 bytes
2025-12-06 03:10:51.530 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-12-06 03:10:51.531 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-12-06 03:10:51.531 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-12-06 03:10:51.531 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-12-06 03:10:51.543 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteEquiJoin
2025-12-06 03:10:51.561 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteEquiJoin
2025-12-06 03:10:51.574 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteEquiJoin
2025-12-06 03:10:51.592 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage<false>
2025-12-06 03:10:51.592 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage<false>
2025-12-06 03:10:51.604 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin<false>
2025-12-06 03:10:51.623 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] dqs_opt.cpp:220: {dummy_op} DqReplicateStageMultiOutput
2025-12-06 03:10:51.636 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-BuildFlatmapStage<true>
2025-12-06 03:10:51.650 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-BuildJoin<true>
2025-12-06 03:10:51.663 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-PushExtractMembersToStage<false>
2025-12-06 03:10:51.683 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage<false>
2025-12-06 03:10:51.699 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] dqs_opt.cpp:220: {dummy_op} DqReplicateStageMultiOutput
2025-12-06 03:10:51.713 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-PushExtractMembersToStage<true>
2025-12-06 03:10:51.727 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-PushExtractMembersToStage<true>
2025-12-06 03:10:51.741 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-BuildJoin<true>
2025-12-06 03:10:51.756 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-PushExtractMembersToStage<false>
2025-12-06 03:10:51.778 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage<false>
2025-12-06 03:10:51.794 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] dqs_opt.cpp:220: {dummy_op} DqReplicateStageMultiOutput
2025-12-06 03:10:51.807 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-PushExtractMembersToStage<true>
2025-12-06 03:10:51.825 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-BuildJoin<true>
2025-12-06 03:10:51.845 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-BuildFlatmapStage<true>
2025-12-06 03:10:51.864 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-BuildJoin<true>
2025-12-06 03:10:51.880 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin<false>
2025-12-06 03:10:51.896 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-PushExtractMembersToStage<false>
2025-12-06 03:10:51.919 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage<false>
2025-12-06 03:10:51.929 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate
2025-12-06 03:10:51.938 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate
2025-12-06 03:10:51.949 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate
2025-12-06 03:10:51.965 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-PushCombineToStage<false>
2025-12-06 03:10:51.983 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate
2025-12-06 03:10:51.993 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate
2025-12-06 03:10:52.014 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildShuffleStage<false>
2025-12-06 03:10:52.026 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildTopSortStage<false>
2025-12-06 03:10:52.052 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [RESULT] yql_result_provider.cpp:771: {dummy_op} ResPull
2025-12-06 03:10:52.082 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core] yql_linear_checker.cpp:210: {dummy_op} Scanned 0 static linear types
2025-12-06 03:10:52.083 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:61: {dummy_op} Begin, root #219573
2025-12-06 03:10:52.083 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:74: {dummy_op} Collect unused nodes for root #219573, status: Ok
2025-12-06 03:10:52.083 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:630: {dummy_op} Node #209721<Configure!> finished execution
2025-12-06 03:10:52.083 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:647: {dummy_op} Node #209721<Configure!> created 0 trackable nodes: 
2025-12-06 03:10:52.083 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:630: {dummy_op} Node #219561<DqCnResult> finished execution
2025-12-06 03:10:52.083 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:647: {dummy_op} Node #219561<DqCnResult> created 0 trackable nodes: 
2025-12-06 03:10:52.204 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 220539
2025-12-06 03:10:52.204 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 220660
2025-12-06 03:10:52.205 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 221278
2025-12-06 03:10:52.206 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 221392
2025-12-06 03:10:52.207 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 221278
2025-12-06 03:10:52.208 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 221392
2025-12-06 03:10:52.221 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 219839
2025-12-06 03:10:52.221 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 219948
2025-12-06 03:10:52.222 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 220782
2025-12-06 03:10:52.223 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 220888
2025-12-06 03:10:52.224 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 220782
2025-12-06 03:10:52.224 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 220888
2025-12-06 03:10:52.237 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 220058
2025-12-06 03:10:52.238 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 220157
2025-12-06 03:10:52.238 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 220058
2025-12-06 03:10:52.238 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 220157
2025-12-06 03:10:52.250 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 217512
2025-12-06 03:10:52.251 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 218244
2025-12-06 03:10:52.259 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 218158
2025-12-06 03:10:52.259 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 218244
2025-12-06 03:10:52.268 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 218348
2025-12-06 03:10:52.269 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 216540
2025-12-06 03:10:52.278 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 218971
2025-12-06 03:10:52.286 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 219416
2025-12-06 03:10:52.294 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219571/#219583} Enabled wide channels for stage with logical id = 219429
2025-12-06 03:10:52.585 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #219839 to Partial
2025-12-06 03:10:52.585 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #220539 to Full
2025-12-06 03:10:52.585 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #220660 to Partial
2025-12-06 03:10:52.585 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #220782 to Partial
2025-12-06 03:10:52.585 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #221278 to Full
2025-12-06 03:10:52.585 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #221392 to Partial
2025-12-06 03:10:52.585 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #220157 to Full
2025-12-06 03:10:52.585 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #220782 to Partial
2025-12-06 03:10:52.585 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #221278 to Full
2025-12-06 03:10:52.586 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #221392 to Partial
2025-12-06 03:10:52.586 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #220157 to Full
2025-12-06 03:10:52.586 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #216540 to Partial
2025-12-06 03:10:52.589 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #219948 to None
2025-12-06 03:10:52.589 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #220888 to None
2025-12-06 03:10:52.590 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #220888 to None
2025-12-06 03:10:52.592 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #217512 to None
2025-12-06 03:10:52.592 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #220058 to None
2025-12-06 03:10:52.592 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #220058 to None
2025-12-06 03:10:52.595 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #218244 to None
2025-12-06 03:10:52.595 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #218244 to None
2025-12-06 03:10:52.598 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #218158 to None
2025-12-06 03:10:52.600 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #218348 to None
2025-12-06 03:10:52.603 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #218971 to None
2025-12-06 03:10:52.606 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #219416 to None
2025-12-06 03:10:52.609 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #219429 to None
2025-12-06 03:10:52.611 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219571/#219583} Setting block status for stage #219552 to None
2025-12-06 03:10:52.638 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:519: {dummy_op} Register async execution for node #219571
2025-12-06 03:10:52.638 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:89: {dummy_op} Finish, output #219573, status: Async
2025-12-06 03:10:52.642 INFO  dqrun(pid=3262926, tid=0x00007FC97E144640) [DQ] executer_actor.cpp:157: {dummy_op} Trying to allocate 26 workers [EnableComputeActor=1]
2025-12-06 03:10:52.901 INFO  dqrun(pid=3262926, tid=0x00007FC976509640) [DQ] executer_actor.cpp:415: {dummy_op} 26 workers allocated
2025-12-06 03:11:14.451 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:135: {dummy_op} Completed async execution for node #219571
2025-12-06 03:11:14.451 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [DQ] yql_dq_exectransformer.cpp:1526: {dummy_op} Execution Pull complete, duration: 22.368165s
2025-12-06 03:11:14.452 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:155: {dummy_op} State is ExecutionComplete after apply async changes for node #219571
2025-12-06 03:11:14.452 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:61: {dummy_op} Begin, root #219573
2025-12-06 03:11:14.452 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:74: {dummy_op} Collect unused nodes for root #219573, status: Ok
2025-12-06 03:11:14.452 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:630: {dummy_op} Node #219572<Commit!> finished execution
2025-12-06 03:11:14.452 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:647: {dummy_op} Node #219572<Commit!> created 0 trackable nodes: 
2025-12-06 03:11:14.452 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:630: {dummy_op} Node #219573<Commit!> finished execution
2025-12-06 03:11:14.452 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:647: {dummy_op} Node #219573<Commit!> created 0 trackable nodes: 
2025-12-06 03:11:14.452 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:89: {dummy_op} Finish, output #219573, status: Ok
2025-12-06 03:11:14.452 INFO  dqrun(pid=3262926, tid=0x00007FC99B1D0380) [core exec] yql_execution.cpp:95: {dummy_op} Creating finalizing transformer, output #219573
Getting results...

Done