dqrun ABI version: 2.44.0 2025-12-06 03:04:07.149 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [default] storage.cpp:189: FileStorage initialized in "/home/runner/.ya/build/build_root/o4ua/005281/r3tmp/tmp6byrb5/", temporary dir: "/home/runner/.ya/build/build_root/o4ua/005281/r3tmp/tmp6byrb5/3233422", locks dir:"/home/runner/.ya/build/build_root/o4ua/005281/r3tmp/tmp6byrb5/locks", files: 0, total size: 0 2025-12-06 03:04:07.156 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [generic] client.cpp:436: Connector[default] endpoint: grpcs://connector.yqv2-dev.cloud.yandex.net:50051 2025-12-06 03:04:07.171 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [http gw] yql_dns_gateway.h:85: Filled DNS resolution table based on provided configuration 2025-12-06 03:04:07.171 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [http gw] yql_dns_gateway.h:126: Started DNS table update 2025-12-06 03:04:07.176 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] interconnect_helpers.cpp:215: Start listener ::1:31337 socket: 18 2025-12-06 03:04:07.200 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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:04:07.358 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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:04:07.362 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_dq_gateway.cpp:605: {dummy_op} OpenSession 2025-12-06 03:04:07.365 INFO dqrun(pid=3233422, tid=0x00007F5D3B8FD640) [DQ] yql_dq_gateway.cpp:633: {dummy_op} OpenSession OK 2025-12-06 03:04:07.371 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [YT] yql_yt_io_discovery.cpp:303: {dummy_op} YtIODiscovery - finish, status: Ok 2025-12-06 03:04:07.371 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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=,.AwsSigV4=}} with options TS3ListingOptions{.IsPartitionedDataset=0,.IsConcurrentListing=0} 2025-12-06 03:04:07.373 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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:04:07.373 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000001s and ended with 1 entries 2025-12-06 03:04:07.373 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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=,.AwsSigV4=}} with options TS3ListingOptions{.IsPartitionedDataset=0,.IsConcurrentListing=0} 2025-12-06 03:04:07.374 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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:04:07.374 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000000s and ended with 1 entries 2025-12-06 03:04:07.374 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_listing_strategy.cpp:1018: {dummy_op} [TLoggingS3ListingStrategy] Going to list request [TS3Lister] TListingRequest{.url=file://./tpc/,.Prefix=h/1/customer/,.Pattern=h/1/customer/*,.PatternType=Wildcard,.Credentials=TS3Credentials{.ServiceAccountAuth=1,.AwsUserPwd=,.AwsSigV4=}} with options TS3ListingOptions{.IsPartitionedDataset=0,.IsConcurrentListing=0} 2025-12-06 03:04:07.375 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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:04:07.375 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000000s and ended with 30 entries 2025-12-06 03:04:07.375 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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=,.AwsSigV4=}} with options TS3ListingOptions{.IsPartitionedDataset=0,.IsConcurrentListing=0} 2025-12-06 03:04:07.376 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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:04:07.376 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000001s and ended with 30 entries 2025-12-06 03:04:07.376 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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=,.AwsSigV4=}} with options TS3ListingOptions{.IsPartitionedDataset=0,.IsConcurrentListing=0} 2025-12-06 03:04:07.377 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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:04:07.377 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000000s and ended with 30 entries 2025-12-06 03:04:07.377 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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=,.AwsSigV4=}} with options TS3ListingOptions{.IsPartitionedDataset=0,.IsConcurrentListing=0} 2025-12-06 03:04:07.378 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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:04:07.378 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000000s and ended with 30 entries 2025-12-06 03:04:07.378 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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=,.AwsSigV4=}} with options TS3ListingOptions{.IsPartitionedDataset=0,.IsConcurrentListing=0} 2025-12-06 03:04:07.379 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [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:04:07.379 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000000s and ended with 30 entries 2025-12-06 03:04:07.380 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/part/* has 30 items with total size 6089692 2025-12-06 03:04:07.380 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/supplier/* has 30 items with total size 825819 2025-12-06 03:04:07.381 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/lineitem/* has 30 items with total size 188300741 2025-12-06 03:04:07.381 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/nation/* has 1 items with total size 5031 2025-12-06 03:04:07.381 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/customer/* has 30 items with total size 10260256 2025-12-06 03:04:07.382 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/region/* has 1 items with total size 3524 2025-12-06 03:04:07.382 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/orders/* has 30 items with total size 54575075 2025-12-06 03:04:07.382 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/nation/* has 1 items with total size 5031 2025-12-06 03:04:07.383 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [YT] yql_yt_io_discovery.cpp:303: {dummy_op} YtIODiscovery - finish, status: Ok 2025-12-06 03:04:07.386 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [RESULT] yql_result_provider.cpp:1415: {dummy_op} RewriteIO 2025-12-06 03:04:07.521 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [RESULT] yql_result_provider.cpp:1154: {dummy_op} Setting result column order: [(o_year->o_year), (mkt_share->mkt_share)] 2025-12-06 03:04:07.842 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:04:07.843 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:04:07.843 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:04:07.844 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:04:07.844 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:04:07.845 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:04:07.845 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:04:07.846 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:04:07.846 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_dq_recapture.cpp:111: {dummy_op} DqsRecapture 2025-12-06 03:04:07.860 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:04:07.860 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:04:07.860 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:04:07.860 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:04:07.860 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:04:07.861 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:04:07.861 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:04:07.861 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:04:07.861 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:04:07.861 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:04:07.861 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:04:07.861 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:04:07.861 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:04:07.861 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:04:07.861 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:04:07.861 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:04:07.876 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 153 files with total size 260065169 bytes 2025-12-06 03:04:07.881 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:04:07.894 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 153 files with total size 260065169 bytes 2025-12-06 03:04:07.896 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:04:07.907 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 153 files with total size 260065169 bytes 2025-12-06 03:04:07.909 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:04:07.920 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 153 files with total size 260065169 bytes 2025-12-06 03:04:07.921 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:04:07.931 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 153 files with total size 260065169 bytes 2025-12-06 03:04:07.933 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:04:07.941 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 153 files with total size 260065169 bytes 2025-12-06 03:04:07.942 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:04:07.950 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 153 files with total size 260065169 bytes 2025-12-06 03:04:07.951 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:04:07.962 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 153 files with total size 260065169 bytes 2025-12-06 03:04:07.964 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:04:07.970 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 153 files with total size 260065169 bytes 2025-12-06 03:04:07.971 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:04:07.971 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:04:07.971 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:04:07.971 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:04:07.971 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:04:07.971 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:04:07.971 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:04:07.972 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:04:07.982 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteEquiJoin 2025-12-06 03:04:08.004 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage 2025-12-06 03:04:08.005 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage 2025-12-06 03:04:08.005 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage 2025-12-06 03:04:08.015 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin 2025-12-06 03:04:08.025 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin 2025-12-06 03:04:08.035 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin 2025-12-06 03:04:08.046 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin 2025-12-06 03:04:08.058 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin 2025-12-06 03:04:08.071 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin 2025-12-06 03:04:08.084 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin 2025-12-06 03:04:08.099 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-PushExtractMembersToStage 2025-12-06 03:04:08.121 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage 2025-12-06 03:04:08.131 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate 2025-12-06 03:04:08.141 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate 2025-12-06 03:04:08.150 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate 2025-12-06 03:04:08.170 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-PushCombineToStage 2025-12-06 03:04:08.189 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate 2025-12-06 03:04:08.201 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate 2025-12-06 03:04:08.243 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildShuffleStage 2025-12-06 03:04:08.256 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildSortStage 2025-12-06 03:04:08.280 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [RESULT] yql_result_provider.cpp:771: {dummy_op} ResPull 2025-12-06 03:04:08.306 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core] yql_linear_checker.cpp:210: {dummy_op} Scanned 0 static linear types 2025-12-06 03:04:08.307 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:61: {dummy_op} Begin, root #228866 2025-12-06 03:04:08.307 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:74: {dummy_op} Collect unused nodes for root #228866, status: Ok 2025-12-06 03:04:08.307 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:630: {dummy_op} Node #209737 finished execution 2025-12-06 03:04:08.308 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:647: {dummy_op} Node #209737 created 0 trackable nodes: 2025-12-06 03:04:08.308 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:630: {dummy_op} Node #228854 finished execution 2025-12-06 03:04:08.308 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:647: {dummy_op} Node #228854 created 0 trackable nodes: 2025-12-06 03:04:08.323 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 224503 2025-12-06 03:04:08.323 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 223784 2025-12-06 03:04:08.333 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 224736 2025-12-06 03:04:08.333 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 223812 2025-12-06 03:04:08.341 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 224886 2025-12-06 03:04:08.341 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 223840 2025-12-06 03:04:08.349 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 225054 2025-12-06 03:04:08.350 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 224545 2025-12-06 03:04:08.357 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 225249 2025-12-06 03:04:08.357 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 223900 2025-12-06 03:04:08.364 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 225466 2025-12-06 03:04:08.365 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 223928 2025-12-06 03:04:08.373 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 225702 2025-12-06 03:04:08.374 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 224588 2025-12-06 03:04:08.382 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 227770 2025-12-06 03:04:08.389 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 228691 2025-12-06 03:04:08.395 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] dq_opt_build.cpp:482: {dummy_op/#228864/#228876} Enabled wide channels for stage with logical id = 228703 2025-12-06 03:04:08.523 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #224503 to Partial 2025-12-06 03:04:08.523 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #223784 to Full 2025-12-06 03:04:08.524 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #223812 to Full 2025-12-06 03:04:08.524 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #223840 to Full 2025-12-06 03:04:08.524 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #224545 to Partial 2025-12-06 03:04:08.524 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #223900 to Full 2025-12-06 03:04:08.524 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #223928 to Full 2025-12-06 03:04:08.524 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #224588 to Partial 2025-12-06 03:04:08.527 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #224736 to None 2025-12-06 03:04:08.529 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #224886 to None 2025-12-06 03:04:08.532 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #225054 to None 2025-12-06 03:04:08.534 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #225249 to None 2025-12-06 03:04:08.537 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #225466 to None 2025-12-06 03:04:08.539 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #225702 to None 2025-12-06 03:04:08.541 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #227770 to None 2025-12-06 03:04:08.543 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #228691 to None 2025-12-06 03:04:08.546 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #228703 to None 2025-12-06 03:04:08.548 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#228864/#228876} Setting block status for stage #228845 to None 2025-12-06 03:04:08.574 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:519: {dummy_op} Register async execution for node #228864 2025-12-06 03:04:08.574 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:89: {dummy_op} Finish, output #228866, status: Async 2025-12-06 03:04:08.578 INFO dqrun(pid=3233422, tid=0x00007F5D66433640) [DQ] executer_actor.cpp:157: {dummy_op} Trying to allocate 18 workers [EnableComputeActor=1] 2025-12-06 03:04:08.689 INFO dqrun(pid=3233422, tid=0x00007F5D62D1A640) [DQ] executer_actor.cpp:415: {dummy_op} 18 workers allocated 2025-12-06 03:04:20.399 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:135: {dummy_op} Completed async execution for node #228864 2025-12-06 03:04:20.399 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [DQ] yql_dq_exectransformer.cpp:1526: {dummy_op} Execution Pull complete, duration: 12.091172s 2025-12-06 03:04:20.399 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:155: {dummy_op} State is ExecutionComplete after apply async changes for node #228864 2025-12-06 03:04:20.399 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:61: {dummy_op} Begin, root #228866 2025-12-06 03:04:20.399 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:74: {dummy_op} Collect unused nodes for root #228866, status: Ok 2025-12-06 03:04:20.400 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:630: {dummy_op} Node #228865 finished execution 2025-12-06 03:04:20.400 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:647: {dummy_op} Node #228865 created 0 trackable nodes: 2025-12-06 03:04:20.400 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:630: {dummy_op} Node #228866 finished execution 2025-12-06 03:04:20.400 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:647: {dummy_op} Node #228866 created 0 trackable nodes: 2025-12-06 03:04:20.400 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:89: {dummy_op} Finish, output #228866, status: Ok 2025-12-06 03:04:20.400 INFO dqrun(pid=3233422, tid=0x00007F5D8571A380) [core exec] yql_execution.cpp:95: {dummy_op} Creating finalizing transformer, output #228866 Getting results... Done