See <https://ci-beam.apache.org/job/beam_LoadTests_Java_CoGBK_Dataflow_V2_Streaming_Java17/83/display/redirect?page=changes>
Changes: [noreply] [BEAM-14072] [BEAM-13993] [BEAM-10039] Import beam plugins before ------------------------------------------ [...truncated 57.75 KB...] Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:39.698Z: Worker configuration: e2-standard-2 in us-central1-b. Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.434Z: Expanding SplittableParDo operations into optimizable parts. Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.472Z: Expanding CollectionToSingleton operations into optimizable parts. Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.529Z: Expanding CoGroupByKey operations into optimizable parts. Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.596Z: Expanding SplittableProcessKeyed operations into optimizable parts. Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.627Z: Expanding GroupByKey operations into streaming Read/Write steps Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.693Z: Lifting ValueCombiningMappingFns into MergeBucketsMappingFns Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.785Z: Fusing adjacent ParDo, Read, Write, and Flatten operations Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.821Z: Unzipping flatten CoGroupByKey-Flatten for input CoGroupByKey-MakeUnionTable0-ParMultiDo-ConstructUnionTable-.output Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.842Z: Fusing unzipped copy of CoGroupByKey/GBK/WriteStream, through flatten CoGroupByKey/Flatten, into producer CoGroupByKey/MakeUnionTable0/ParMultiDo(ConstructUnionTable) Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.865Z: Fusing consumer CoGroupByKey/GBK/WriteStream into CoGroupByKey/MakeUnionTable1/ParMultiDo(ConstructUnionTable) Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.898Z: Fusing consumer Read input/ParDo(OutputSingleSource)/ParMultiDo(OutputSingleSource) into Read input/Impulse Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.918Z: Fusing consumer Read-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/PairWithRestriction into Read input/ParDo(OutputSingleSource)/ParMultiDo(OutputSingleSource) Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.951Z: Fusing consumer Read-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/SplitWithSizing into Read-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/PairWithRestriction Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:40.986Z: Fusing consumer Read input/ParDo(StripIds)/ParMultiDo(StripIds) into Read-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/ProcessElementAndRestrictionWithSizing Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.015Z: Fusing consumer Collect start time metrics (input)/ParMultiDo(TimeMonitor) into Read input/ParDo(StripIds)/ParMultiDo(StripIds) Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.050Z: Fusing consumer Window.Into()/Window.Assign into Collect start time metrics (input)/ParMultiDo(TimeMonitor) Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.124Z: Fusing consumer CoGroupByKey/MakeUnionTable0/ParMultiDo(ConstructUnionTable) into Window.Into()/Window.Assign Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.158Z: Fusing consumer Read co-input/ParDo(OutputSingleSource)/ParMultiDo(OutputSingleSource) into Read co-input/Impulse Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.202Z: Fusing consumer Read-co-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/PairWithRestriction into Read co-input/ParDo(OutputSingleSource)/ParMultiDo(OutputSingleSource) Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.233Z: Fusing consumer Read-co-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/SplitWithSizing into Read-co-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/PairWithRestriction Mar 13, 2022 12:32:41 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.256Z: Fusing consumer Read co-input/ParDo(StripIds)/ParMultiDo(StripIds) into Read-co-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/ProcessElementAndRestrictionWithSizing Mar 13, 2022 12:32:44 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.289Z: Fusing consumer Collect start time metrics (co-input)/ParMultiDo(TimeMonitor) into Read co-input/ParDo(StripIds)/ParMultiDo(StripIds) Mar 13, 2022 12:32:44 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.318Z: Fusing consumer Window.Into()2/Window.Assign into Collect start time metrics (co-input)/ParMultiDo(TimeMonitor) Mar 13, 2022 12:32:44 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.343Z: Fusing consumer CoGroupByKey/MakeUnionTable1/ParMultiDo(ConstructUnionTable) into Window.Into()2/Window.Assign Mar 13, 2022 12:32:44 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.380Z: Fusing consumer CoGroupByKey/GBK/MergeBuckets into CoGroupByKey/GBK/ReadStream Mar 13, 2022 12:32:44 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.410Z: Fusing consumer CoGroupByKey/ConstructCoGbkResultFn/ParMultiDo(ConstructCoGbkResult) into CoGroupByKey/GBK/MergeBuckets Mar 13, 2022 12:32:44 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.437Z: Fusing consumer Ungroup and reiterate/ParMultiDo(UngroupAndReiterate) into CoGroupByKey/ConstructCoGbkResultFn/ParMultiDo(ConstructCoGbkResult) Mar 13, 2022 12:32:44 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.467Z: Fusing consumer Collect total bytes/ParMultiDo(ByteMonitor) into Ungroup and reiterate/ParMultiDo(UngroupAndReiterate) Mar 13, 2022 12:32:44 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.491Z: Fusing consumer Collect end time metrics/ParMultiDo(TimeMonitor) into Collect total bytes/ParMultiDo(ByteMonitor) Mar 13, 2022 12:32:44 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.636Z: Running job using Streaming Engine Mar 13, 2022 12:32:44 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:41.853Z: Starting 5 ****s in us-central1-b... Mar 13, 2022 12:32:50 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:32:48.686Z: Your project already contains 100 Dataflow-created metric descriptors, so new user metrics of the form custom.googleapis.com/* will not be created. However, all user metrics are also available in the metric dataflow.googleapis.com/job/user_counter. If you rely on the custom metrics, you can delete old / unused metric descriptors. See https://developers.google.com/apis-explorer/#p/monitoring/v3/monitoring.projects.metricDescriptors.list and https://developers.google.com/apis-explorer/#p/monitoring/v3/monitoring.projects.metricDescriptors.delete Mar 13, 2022 12:33:22 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:33:21.484Z: Autoscaling: Raised the number of ****s to 5 so that the pipeline can catch up with its backlog and keep up with its input rate. Mar 13, 2022 12:34:20 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:34:19.392Z: Workers have started successfully. Mar 13, 2022 12:34:20 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T12:34:19.434Z: Workers have started successfully. Mar 13, 2022 12:37:22 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process SEVERE: 2022-03-13T12:37:20.675Z: generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 generic::internal: The work item requesting state read is no longer valid on the backend. The work has already completed or will be retried. This is expected during autoscaling events. passed through: ==> dist_proc/windmill/client/streaming_rpc_client.cc:697 ==> dist_proc/dax/workflow/****/streaming/merge_windows_fn.cc:230 Mar 13, 2022 4:00:35 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T16:00:34.486Z: Cancel request is committed for workflow job: 2022-03-13_05_32_28-11238988837831007706. Mar 13, 2022 4:00:35 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T16:00:34.560Z: Cleaning up. Mar 13, 2022 4:00:35 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T16:00:34.728Z: Stopping **** pool... Mar 13, 2022 4:00:35 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T16:00:34.783Z: Stopping **** pool... Mar 13, 2022 4:03:04 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T16:03:01.939Z: Autoscaling: Reduced the number of ****s to 0 based on low average **** CPU utilization, and the pipeline having sufficiently low backlog and keeping up with input rate. Mar 13, 2022 4:03:04 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2022-03-13T16:03:01.987Z: Worker pool stopped. Mar 13, 2022 4:03:08 PM org.apache.beam.runners.dataflow.DataflowPipelineJob logTerminalState INFO: Job 2022-03-13_05_32_28-11238988837831007706 finished with status CANCELLED. Load test results for test (ID): 944cca84-7605-4d25-bc23-397d46a9fe07 and timestamp: 2022-03-13T12:32:23.515000000Z: Metric: Value: dataflow_v2_java17_runtime_sec 12345.032 dataflow_v2_java17_total_bytes_count 1.99644701E10 Exception in thread "main" java.lang.RuntimeException: Invalid job state: CANCELLED. at org.apache.beam.sdk.loadtests.JobFailure.handleFailure(JobFailure.java:51) at org.apache.beam.sdk.loadtests.LoadTest.run(LoadTest.java:139) at org.apache.beam.sdk.loadtests.CoGroupByKeyLoadTest.run(CoGroupByKeyLoadTest.java:62) at org.apache.beam.sdk.loadtests.CoGroupByKeyLoadTest.main(CoGroupByKeyLoadTest.java:157) > Task :sdks:java:testing:load-tests:run FAILED > Task :runners:google-cloud-dataflow-java:cleanUpDockerJavaImages Untagged: us.gcr.io/apache-beam-testing/java-postcommit-it/java:20220313123035 Untagged: us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:8382c89a8536286988a3d1425f2629a9ac3977cc9ea64e5a577076832009de78 Tag: [us.gcr.io/apache-beam-testing/java-postcommit-it/java:20220313123035] - referencing digest: [us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:8382c89a8536286988a3d1425f2629a9ac3977cc9ea64e5a577076832009de78] Deleted [[us.gcr.io/apache-beam-testing/java-postcommit-it/java:20220313123035] (referencing [us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:8382c89a8536286988a3d1425f2629a9ac3977cc9ea64e5a577076832009de78])]. Removing untagged image us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:8382c89a8536286988a3d1425f2629a9ac3977cc9ea64e5a577076832009de78 Digests: - us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:8382c89a8536286988a3d1425f2629a9ac3977cc9ea64e5a577076832009de78 Deleted [us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:8382c89a8536286988a3d1425f2629a9ac3977cc9ea64e5a577076832009de78]. FAILURE: Build failed with an exception. * What went wrong: Execution failed for task ':sdks:java:testing:load-tests:run'. > Process 'command '/usr/lib/jvm/java-8-openjdk-amd64/bin/java'' finished with > non-zero exit value 1 * Try: > Run with --stacktrace option to get the stack trace. > Run with --info or --debug option to get more log output. > Run with --scan to get full insights. * Get more help at https://help.gradle.org Deprecated Gradle features were used in this build, making it incompatible with Gradle 8.0. You can use '--warning-mode all' to show the individual deprecation warnings and determine if they come from your own scripts or plugins. See https://docs.gradle.org/7.3.2/userguide/command_line_interface.html#sec:command_line_warnings Execution optimizations have been disabled for 1 invalid unit(s) of work during this build to ensure correctness. Please consult deprecation warnings for more details. BUILD FAILED in 3h 32m 54s 109 actionable tasks: 72 executed, 33 from cache, 4 up-to-date Publishing build scan... https://gradle.com/s/gkvnhrapq5la2 Build step 'Invoke Gradle script' changed build result to FAILURE Build step 'Invoke Gradle script' marked build as failure --------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
