dqrun ABI version: 2.44.0 2025-12-06 03:08:48.963 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [default] storage.cpp:189: FileStorage initialized in "/home/runner/.ya/build/build_root/o4ua/005281/r3tmp/tmpaa3RHL/", temporary dir: "/home/runner/.ya/build/build_root/o4ua/005281/r3tmp/tmpaa3RHL/3259140", locks dir:"/home/runner/.ya/build/build_root/o4ua/005281/r3tmp/tmpaa3RHL/locks", files: 0, total size: 0 2025-12-06 03:08:48.972 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [generic] client.cpp:436: Connector[default] endpoint: grpcs://connector.yqv2-dev.cloud.yandex.net:50051 2025-12-06 03:08:48.993 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [http gw] yql_dns_gateway.h:85: Filled DNS resolution table based on provided configuration 2025-12-06 03:08:48.993 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [http gw] yql_dns_gateway.h:126: Started DNS table update 2025-12-06 03:08:48.999 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] interconnect_helpers.cpp:215: Start listener ::1:31337 socket: 18 2025-12-06 03:08:49.028 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.184 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.188 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_dq_gateway.cpp:605: {dummy_op} OpenSession 2025-12-06 03:08:49.195 INFO dqrun(pid=3259140, tid=0x00007F9AC1905640) [DQ] yql_dq_gateway.cpp:633: {dummy_op} OpenSession OK 2025-12-06 03:08:49.198 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [YT] yql_yt_io_discovery.cpp:303: {dummy_op} YtIODiscovery - finish, status: Ok 2025-12-06 03:08:49.198 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.200 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.200 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000001s and ended with 1 entries 2025-12-06 03:08:49.200 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.201 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.201 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000001s and ended with 30 entries 2025-12-06 03:08:49.201 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.203 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.203 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000000s and ended with 30 entries 2025-12-06 03:08:49.203 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.204 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.204 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000001s and ended with 30 entries 2025-12-06 03:08:49.204 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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=,.AwsSigV4=}} with options TS3ListingOptions{.IsPartitionedDataset=0,.IsConcurrentListing=0} 2025-12-06 03:08:49.206 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.206 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000000s and ended with 30 entries 2025-12-06 03:08:49.206 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.207 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [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:08:49.208 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_listing_strategy.cpp:1026: {dummy_op} [TLoggingS3ListingStrategy] Listing took 0.000001s and ended with 30 entries 2025-12-06 03:08:49.208 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/part/* has 30 items with total size 6089692 2025-12-06 03:08:49.209 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/partsupp/* has 30 items with total size 34281114 2025-12-06 03:08:49.209 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/nation/* has 1 items with total size 5031 2025-12-06 03:08:49.209 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/supplier/* has 30 items with total size 825819 2025-12-06 03:08:49.210 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/orders/* has 30 items with total size 54575075 2025-12-06 03:08:49.210 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_io_discovery.cpp:395: {dummy_op} Pattern h/1/lineitem/* has 30 items with total size 188300741 2025-12-06 03:08:49.212 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [YT] yql_yt_io_discovery.cpp:303: {dummy_op} YtIODiscovery - finish, status: Ok 2025-12-06 03:08:49.216 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [RESULT] yql_result_provider.cpp:1415: {dummy_op} RewriteIO 2025-12-06 03:08:49.365 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [RESULT] yql_result_provider.cpp:1154: {dummy_op} Setting result column order: [(nation->nation), (o_year->o_year), (sum_profit->sum_profit)] 2025-12-06 03:08:49.467 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:08:49.467 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:08:49.467 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:08:49.468 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:08:49.469 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:08:49.469 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_dq_integration.cpp:252: {dummy_op} Wrap S3ReadObject! with token: cluster:default_yq-tpc-local 2025-12-06 03:08:49.470 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_dq_recapture.cpp:111: {dummy_op} DqsRecapture 2025-12-06 03:08:49.484 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:08:49.484 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:08:49.484 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:08:49.484 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:08:49.484 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:08:49.484 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:08:49.484 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:08:49.484 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:08:49.484 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:08:49.484 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:08:49.485 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:628: {dummy_op} ExtractMembers over DqSource with S3ParseSettings 2025-12-06 03:08:49.485 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_optimize.cpp:131: {dummy_op} LogicalOptimizer-ExtractMembersOverDqSource 2025-12-06 03:08:49.501 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 151 files with total size 284077472 bytes 2025-12-06 03:08:49.507 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:08:49.522 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 151 files with total size 284077472 bytes 2025-12-06 03:08:49.525 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:08:49.537 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 151 files with total size 284077472 bytes 2025-12-06 03:08:49.540 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:08:49.555 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 151 files with total size 284077472 bytes 2025-12-06 03:08:49.557 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:08:49.570 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 151 files with total size 284077472 bytes 2025-12-06 03:08:49.572 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:08:49.583 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 151 files with total size 284077472 bytes 2025-12-06 03:08:49.585 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_optimize.cpp:131: {dummy_op} PhysicalOptimizer-TrimReadWorld 2025-12-06 03:08:49.594 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [S3] yql_s3_logical_opt.cpp:434: {dummy_op} Will read from S3 151 files with total size 284077472 bytes 2025-12-06 03:08:49.595 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:08:49.595 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:08:49.595 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:08:49.595 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:08:49.595 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:08:49.595 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildStageWithSourceWrap 2025-12-06 03:08:49.610 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteEquiJoin 2025-12-06 03:08:49.642 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage 2025-12-06 03:08:49.655 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin 2025-12-06 03:08:49.669 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin 2025-12-06 03:08:49.685 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin 2025-12-06 03:08:49.702 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin 2025-12-06 03:08:49.717 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildJoin 2025-12-06 03:08:49.736 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-PushExtractMembersToStage 2025-12-06 03:08:49.767 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildFlatmapStage 2025-12-06 03:08:49.781 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate 2025-12-06 03:08:49.793 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate 2025-12-06 03:08:49.805 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate 2025-12-06 03:08:49.834 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-PushCombineToStage 2025-12-06 03:08:49.859 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate 2025-12-06 03:08:49.871 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsLogical-RewriteAggregate 2025-12-06 03:08:49.899 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildShuffleStage 2025-12-06 03:08:49.910 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_optimize.cpp:131: {dummy_op} DqsPhy-BuildSortStage 2025-12-06 03:08:49.938 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [RESULT] yql_result_provider.cpp:771: {dummy_op} ResPull 2025-12-06 03:08:49.969 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core] yql_linear_checker.cpp:210: {dummy_op} Scanned 0 static linear types 2025-12-06 03:08:49.970 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:61: {dummy_op} Begin, root #219525 2025-12-06 03:08:49.970 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:74: {dummy_op} Collect unused nodes for root #219525, status: Ok 2025-12-06 03:08:49.971 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:630: {dummy_op} Node #209726 finished execution 2025-12-06 03:08:49.971 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:647: {dummy_op} Node #209726 created 0 trackable nodes: 2025-12-06 03:08:49.971 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:630: {dummy_op} Node #219513 finished execution 2025-12-06 03:08:49.971 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:647: {dummy_op} Node #219513 created 0 trackable nodes: 2025-12-06 03:08:49.989 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 216031 2025-12-06 03:08:49.989 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 216609 2025-12-06 03:08:49.997 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 216000 2025-12-06 03:08:49.997 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 216714 2025-12-06 03:08:50.005 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 215960 2025-12-06 03:08:50.005 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 216874 2025-12-06 03:08:50.012 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 215932 2025-12-06 03:08:50.013 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 217062 2025-12-06 03:08:50.021 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 215904 2025-12-06 03:08:50.021 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 217268 2025-12-06 03:08:50.032 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 218682 2025-12-06 03:08:50.042 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 219368 2025-12-06 03:08:50.050 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] dq_opt_build.cpp:482: {dummy_op/#219523/#219535} Enabled wide channels for stage with logical id = 219380 2025-12-06 03:08:50.172 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #215904 to Full 2025-12-06 03:08:50.172 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #215932 to Full 2025-12-06 03:08:50.172 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #215960 to Full 2025-12-06 03:08:50.172 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #216000 to Full 2025-12-06 03:08:50.172 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #216031 to Full 2025-12-06 03:08:50.172 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #216609 to Partial 2025-12-06 03:08:50.175 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #216714 to None 2025-12-06 03:08:50.177 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #216874 to None 2025-12-06 03:08:50.180 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #217062 to None 2025-12-06 03:08:50.182 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #217268 to None 2025-12-06 03:08:50.184 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #218682 to None 2025-12-06 03:08:50.186 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #219368 to None 2025-12-06 03:08:50.189 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #219380 to None 2025-12-06 03:08:50.191 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core dq] yql_dq_exectransformer.cpp:379: {dummy_op/#219523/#219535} Setting block status for stage #219504 to None 2025-12-06 03:08:50.220 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:519: {dummy_op} Register async execution for node #219523 2025-12-06 03:08:50.220 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:89: {dummy_op} Finish, output #219525, status: Async 2025-12-06 03:08:50.224 INFO dqrun(pid=3259140, tid=0x00007F9AEC43B640) [DQ] executer_actor.cpp:157: {dummy_op} Trying to allocate 14 workers [EnableComputeActor=1] 2025-12-06 03:08:50.231 INFO dqrun(pid=3259140, tid=0x00007F9AE34FA640) [DQ] executer_actor.cpp:415: {dummy_op} 14 workers allocated 2025-12-06 03:09:13.074 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:135: {dummy_op} Completed async execution for node #219523 2025-12-06 03:09:13.074 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [DQ] yql_dq_exectransformer.cpp:1526: {dummy_op} Execution Pull complete, duration: 23.103042s 2025-12-06 03:09:13.075 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:155: {dummy_op} State is ExecutionComplete after apply async changes for node #219523 2025-12-06 03:09:13.075 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:61: {dummy_op} Begin, root #219525 2025-12-06 03:09:13.075 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:74: {dummy_op} Collect unused nodes for root #219525, status: Ok 2025-12-06 03:09:13.075 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:630: {dummy_op} Node #219524 finished execution 2025-12-06 03:09:13.075 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:647: {dummy_op} Node #219524 created 0 trackable nodes: 2025-12-06 03:09:13.075 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:630: {dummy_op} Node #219525 finished execution 2025-12-06 03:09:13.075 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:647: {dummy_op} Node #219525 created 0 trackable nodes: 2025-12-06 03:09:13.075 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:89: {dummy_op} Finish, output #219525, status: Ok 2025-12-06 03:09:13.075 INFO dqrun(pid=3259140, tid=0x00007F9B0A2B2380) [core exec] yql_execution.cpp:95: {dummy_op} Creating finalizing transformer, output #219525 Getting results... Done