dqrun ABI version: 2.44.0
2025-12-06 03:07:42.471 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [default] storage.cpp:189: FileStorage initialized in "/home/runner/.ya/build/build_root/o4ua/005281/r3tmp/tmpTBXFZY/", temporary dir: "/home/runner/.ya/build/build_root/o4ua/005281/r3tmp/tmpTBXFZY/3255128", locks dir:"/home/runner/.ya/build/build_root/o4ua/005281/r3tmp/tmpTBXFZY/locks", files: 0, total size: 0
2025-12-06 03:07:42.479 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [generic] client.cpp:436: Connector[default] endpoint: grpcs://connector.yqv2-dev.cloud.yandex.net:50051
2025-12-06 03:07:42.498 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [http gw] yql_dns_gateway.h:85: Filled DNS resolution table based on provided configuration
2025-12-06 03:07:42.498 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [http gw] yql_dns_gateway.h:126: Started DNS table update
2025-12-06 03:07:42.504 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] interconnect_helpers.cpp:215: Start listener ::1:31337 socket: 18
2025-12-06 03:07:42.534 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [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:07:42.777 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [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:07:42.783 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_dq_gateway.cpp:605: {dummy_op} OpenSession
2025-12-06 03:07:42.790 INFO  dqrun(pid=3255128, tid=0x00007F6063605640) [DQ] yql_dq_gateway.cpp:633: {dummy_op} OpenSession OK
2025-12-06 03:07:42.797 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [YT] yql_yt_io_discovery.cpp:303: {dummy_op} YtIODiscovery - finish, status: Ok
2025-12-06 03:07:42.798 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [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:07:42.801 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [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:07:42.801 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000001s and ended with 30 entries
2025-12-06 03:07:42.801 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_listing_strategy.cpp:1018: {dummy_op} [TLoggingS3ListingStrategy] Going to list request [TS3Lister] TListingRequest{.url=file://./tpc/,.Prefix=h/1/partsupp/,.Pattern=h/1/partsupp/*,.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:07:42.803 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [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:07:42.803 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000001s and ended with 30 entries
2025-12-06 03:07:42.803 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_listing_strategy.cpp:1018: {dummy_op} [TLoggingS3ListingStrategy] Going to list request [TS3Lister] TListingRequest{.url=file://./tpc/,.Prefix=h/1/part/,.Pattern=h/1/part/*,.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:07:42.805 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [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:07:42.805 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000001s and ended with 30 entries
2025-12-06 03:07:42.805 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [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:07:42.807 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [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:07:42.807 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000001s and ended with 1 entries
2025-12-06 03:07:42.807 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_listing_strategy.cpp:1018: {dummy_op} [TLoggingS3ListingStrategy] Going to list request [TS3Lister] TListingRequest{.url=file://./tpc/,.Prefix=h/1/region/,.Pattern=h/1/region/*,.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:07:42.808 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [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:07:42.809 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000000s and ended with 1 entries
2025-12-06 03:07:42.809 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/nation/* has 1 items with total size 5031
2025-12-06 03:07:42.809 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/supplier/* has 30 items with total size 825819
2025-12-06 03:07:42.810 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/partsupp/* has 30 items with total size 34281114
2025-12-06 03:07:42.811 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/region/* has 1 items with total size 3524
2025-12-06 03:07:42.811 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/supplier/* has 30 items with total size 825819
2025-12-06 03:07:42.811 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/partsupp/* has 30 items with total size 34281114
2025-12-06 03:07:42.812 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/part/* has 30 items with total size 6089692
2025-12-06 03:07:42.815 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [YT] yql_yt_io_discovery.cpp:303: {dummy_op} YtIODiscovery - finish, status: Ok
2025-12-06 03:07:42.819 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [RESULT] yql_result_provider.cpp:1415: {dummy_op} RewriteIO
2025-12-06 03:07:43.000 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [RESULT] yql_result_provider.cpp:1154: {dummy_op} Setting result column order: [(s_acctbal->s_acctbal), (s_name->s_name), (n_name->n_name), (p_partkey->p_partkey), (p_mfgr->p_mfgr), (s_address->s_address), (s_phone->s_phone), (s_comment->s_comment)]
2025-12-06 03:07:43.113 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local
2025-12-06 03:07:43.115 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local
2025-12-06 03:07:43.116 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local
2025-12-06 03:07:43.116 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local
2025-12-06 03:07:43.117 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local
2025-12-06 03:07:43.117 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local
2025-12-06 03:07:43.118 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local
2025-12-06 03:07:43.119 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_dq_recapture.cpp:111: {dummy_op} DqsRecapture
2025-12-06 03:07:43.136 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings
2025-12-06 03:07:43.136 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource
2025-12-06 03:07:43.137 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings
2025-12-06 03:07:43.137 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource
2025-12-06 03:07:43.137 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings
2025-12-06 03:07:43.138 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource
2025-12-06 03:07:43.138 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings
2025-12-06 03:07:43.138 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource
2025-12-06 03:07:43.138 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings
2025-12-06 03:07:43.138 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource
2025-12-06 03:07:43.138 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings
2025-12-06 03:07:43.138 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource
2025-12-06 03:07:43.156 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 152 files with total size 76312113 bytes
2025-12-06 03:07:43.161 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-12-06 03:07:43.177 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 152 files with total size 76312113 bytes
2025-12-06 03:07:43.179 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-12-06 03:07:43.193 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 152 files with total size 76312113 bytes
2025-12-06 03:07:43.195 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-12-06 03:07:43.211 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 152 files with total size 76312113 bytes
2025-12-06 03:07:43.214 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-12-06 03:07:43.228 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 152 files with total size 76312113 bytes
2025-12-06 03:07:43.230 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-12-06 03:07:43.244 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 122 files with total size 42030999 bytes
2025-12-06 03:07:43.245 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-12-06 03:07:43.256 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 122 files with total size 42030999 bytes
2025-12-06 03:07:43.258 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld
2025-12-06 03:07:43.266 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 122 files with total size 42030999 bytes
2025-12-06 03:07:43.267 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-12-06 03:07:43.268 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-12-06 03:07:43.268 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-12-06 03:07:43.268 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-12-06 03:07:43.268 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-12-06 03:07:43.268 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap
2025-12-06 03:07:43.283 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteEquiJoin
2025-12-06 03:07:43.303 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteEquiJoin
2025-12-06 03:07:43.316 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteEquiJoin
2025-12-06 03:07:43.340 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage<false>
2025-12-06 03:07:43.341 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage<false>
2025-12-06 03:07:43.354 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin<false>
2025-12-06 03:07:43.367 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-PushExtractMembersToStage<false>
2025-12-06 03:07:43.393 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage<false>
2025-12-06 03:07:43.413 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] dqs_opt.cpp:220: {dummy_op} DqReplicateStageMultiOutput
2025-12-06 03:07:43.427 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-BuildJoin<true>
2025-12-06 03:07:43.446 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] dqs_opt.cpp:220: {dummy_op} DqReplicateStageMultiOutput
2025-12-06 03:07:43.461 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-BuildJoin<true>
2025-12-06 03:07:43.478 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin<false>
2025-12-06 03:07:43.497 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-PushExtractMembersToStage<true>
2025-12-06 03:07:43.516 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-BuildJoin<true>
2025-12-06 03:07:43.533 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:93: {dummy_op} DqsPhy-BuildJoin<true>
2025-12-06 03:07:43.555 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-PushExtractMembersToStage<false>
2025-12-06 03:07:43.584 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate
2025-12-06 03:07:43.595 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate
2025-12-06 03:07:43.605 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate
2025-12-06 03:07:43.630 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-PushCombineToStage<false>
2025-12-06 03:07:43.644 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate
2025-12-06 03:07:43.655 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate
2025-12-06 03:07:43.677 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildShuffleStage<false>
2025-12-06 03:07:43.691 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin<false>
2025-12-06 03:07:43.706 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-PushExtractMembersToStage<false>
2025-12-06 03:07:43.731 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage<false>
2025-12-06 03:07:43.748 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildTopSortStage<false>
2025-12-06 03:07:43.770 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [RESULT] yql_result_provider.cpp:771: {dummy_op} ResPull
2025-12-06 03:07:43.800 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core] yql_linear_checker.cpp:210: {dummy_op} Scanned 0 static linear types
2025-12-06 03:07:43.802 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:61: {dummy_op} Begin, root #221005
2025-12-06 03:07:43.802 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:74: {dummy_op} Collect unused nodes for root #221005, status: Ok
2025-12-06 03:07:43.802 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:630: {dummy_op} Node #209725<Configure!> finished execution
2025-12-06 03:07:43.802 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:647: {dummy_op} Node #209725<Configure!> created 0 trackable nodes: 
2025-12-06 03:07:43.802 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:630: {dummy_op} Node #220993<DqCnResult> finished execution
2025-12-06 03:07:43.802 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:647: {dummy_op} Node #220993<DqCnResult> created 0 trackable nodes: 
2025-12-06 03:07:43.849 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 218386
2025-12-06 03:07:43.850 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 217906
2025-12-06 03:07:43.850 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 221194
2025-12-06 03:07:43.850 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 221292
2025-12-06 03:07:43.850 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 221194
2025-12-06 03:07:43.851 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 221292
2025-12-06 03:07:43.860 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 217179
2025-12-06 03:07:43.860 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 219022
2025-12-06 03:07:43.861 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 217270
2025-12-06 03:07:43.861 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 219022
2025-12-06 03:07:43.870 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 218502
2025-12-06 03:07:43.870 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 218719
2025-12-06 03:07:43.871 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 218386
2025-12-06 03:07:43.871 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 219120
2025-12-06 03:07:43.881 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 219885
2025-12-06 03:07:43.888 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 218914
2025-12-06 03:07:43.888 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 220243
2025-12-06 03:07:43.896 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 220856
2025-12-06 03:07:43.904 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] dq_opt_build.cpp:482: {dummy_op/#221003/#221015} Enabled wide channels for stage with logical id = 220869
2025-12-06 03:07:44.122 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #218386 to Full
2025-12-06 03:07:44.122 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #217906 to Partial
2025-12-06 03:07:44.122 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #217179 to Full
2025-12-06 03:07:44.122 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #221194 to Full
2025-12-06 03:07:44.122 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #221292 to Partial
2025-12-06 03:07:44.122 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #218386 to Full
2025-12-06 03:07:44.122 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #217270 to Full
2025-12-06 03:07:44.122 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #221194 to Full
2025-12-06 03:07:44.122 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #221292 to Partial
2025-12-06 03:07:44.126 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #218502 to None
2025-12-06 03:07:44.126 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #219022 to None
2025-12-06 03:07:44.126 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #219022 to None
2025-12-06 03:07:44.130 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #218719 to None
2025-12-06 03:07:44.130 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #219120 to None
2025-12-06 03:07:44.133 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #218914 to None
2025-12-06 03:07:44.134 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #219885 to None
2025-12-06 03:07:44.136 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #220243 to None
2025-12-06 03:07:44.139 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #220856 to None
2025-12-06 03:07:44.142 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #220869 to None
2025-12-06 03:07:44.145 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#221003/#221015} Setting block status for stage #220984 to None
2025-12-06 03:07:44.167 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:519: {dummy_op} Register async execution for node #221003
2025-12-06 03:07:44.167 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:89: {dummy_op} Finish, output #221005, status: Async
2025-12-06 03:07:44.170 INFO  dqrun(pid=3255128, tid=0x00007F608EF44640) [DQ] executer_actor.cpp:157: {dummy_op} Trying to allocate 20 workers [EnableComputeActor=1]
2025-12-06 03:07:44.240 INFO  dqrun(pid=3255128, tid=0x00007F608AA22640) [DQ] executer_actor.cpp:415: {dummy_op} 20 workers allocated
2025-12-06 03:07:46.309 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:135: {dummy_op} Completed async execution for node #221003
2025-12-06 03:07:46.309 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [DQ] yql_dq_exectransformer.cpp:1526: {dummy_op} Execution Pull complete, duration: 2.507224s
2025-12-06 03:07:46.312 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:155: {dummy_op} State is ExecutionComplete after apply async changes for node #221003
2025-12-06 03:07:46.312 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:61: {dummy_op} Begin, root #221005
2025-12-06 03:07:46.312 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:74: {dummy_op} Collect unused nodes for root #221005, status: Ok
2025-12-06 03:07:46.312 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:630: {dummy_op} Node #221004<Commit!> finished execution
2025-12-06 03:07:46.312 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:647: {dummy_op} Node #221004<Commit!> created 0 trackable nodes: 
2025-12-06 03:07:46.312 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:630: {dummy_op} Node #221005<Commit!> finished execution
2025-12-06 03:07:46.312 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:647: {dummy_op} Node #221005<Commit!> created 0 trackable nodes: 
2025-12-06 03:07:46.312 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:89: {dummy_op} Finish, output #221005, status: Ok
2025-12-06 03:07:46.312 INFO  dqrun(pid=3255128, tid=0x00007F60ABFA6380) [core exec] yql_execution.cpp:95: {dummy_op} Creating finalizing transformer, output #221005
Getting results...

Done