See <https://ci-beam.apache.org/job/beam_LoadTests_Java_GBK_Dataflow_V2_Streaming_Java11/8/display/redirect?page=changes>
Changes: [noreply] [BEAM-11514] Make schema error more helpful (#15075) [noreply] Minor: Fix missing f-string in nlargest/nsmallest error messages ------------------------------------------ [...truncated 61.40 KB...] > Task :sdks:go:goBuild > Task :sdks:java:container:resolveBuildDependencies Resolving ./github.com/apache/beam/sdks/go@<https://ci-beam.apache.org/job/beam_LoadTests_Java_GBK_Dataflow_V2_Streaming_Java11/ws/src/sdks/go> > Task :sdks:java:container:installDependencies > Task :sdks:java:container:buildLinuxAmd64 > Task :sdks:java:container:goBuild > Task :sdks:java:container:java11:copySdkHarnessLauncher > Task :sdks:java:container:java11:dockerPrepare > Task :sdks:java:container:java11:docker > Task :runners:google-cloud-dataflow-java:buildAndPushDockerContainer WARNING: `gcloud docker` will not be supported for Docker client versions above 18.03. As an alternative, use `gcloud auth configure-docker` to configure `docker` to use `gcloud` as a credential helper, then use `docker` as you would for non-GCR registries, e.g. `docker pull gcr.io/project-id/my-image`. Add `--verbosity=error` to silence this warning: `gcloud docker --verbosity=error -- pull gcr.io/project-id/my-image`. See: https://cloud.google.com/container-registry/docs/support/deprecation-notices#gcloud-docker The push refers to repository [us.gcr.io/apache-beam-testing/java-postcommit-it/java] 36d149c2a6ef: Preparing 6ec957b51537: Preparing 1226f99d3b16: Preparing 0af09c7a36f5: Preparing c3e4d8187a3e: Preparing 42e03483c7d2: Preparing dfe1df0ea4ee: Preparing 0ddf66b2be62: Preparing a42d89dfe610: Preparing 992c904de3ec: Preparing e9d1ccc1b9fb: Preparing 0b5246548260: Preparing 82f8ebb33b27: Preparing c0848348e2f7: Preparing 79c550eb7bd2: Preparing 7095af798ace: Preparing fe6a4fdbedc0: Preparing e4d0e810d54a: Preparing 4e006334a6fd: Preparing 42e03483c7d2: Waiting dfe1df0ea4ee: Waiting c0848348e2f7: Waiting 79c550eb7bd2: Waiting 0ddf66b2be62: Waiting 7095af798ace: Waiting fe6a4fdbedc0: Waiting a42d89dfe610: Waiting 992c904de3ec: Waiting e4d0e810d54a: Waiting 4e006334a6fd: Waiting e9d1ccc1b9fb: Waiting 0b5246548260: Waiting 82f8ebb33b27: Waiting c3e4d8187a3e: Pushed 1226f99d3b16: Pushed 6ec957b51537: Pushed 36d149c2a6ef: Pushed 42e03483c7d2: Pushed 0af09c7a36f5: Pushed 0ddf66b2be62: Pushed a42d89dfe610: Pushed 82f8ebb33b27: Layer already exists c0848348e2f7: Layer already exists 79c550eb7bd2: Layer already exists 7095af798ace: Layer already exists e9d1ccc1b9fb: Pushed fe6a4fdbedc0: Layer already exists e4d0e810d54a: Layer already exists 4e006334a6fd: Layer already exists 0b5246548260: Pushed dfe1df0ea4ee: Pushed 992c904de3ec: Pushed 20210625120630: digest: sha256:3b014a9014ba2b288ceefb7ba8b9edafa86b6b92869b9df181e9a9b32de27451 size: 4310 > Task :sdks:java:testing:load-tests:run Jun 25, 2021 12:13:52 PM org.apache.beam.runners.dataflow.options.DataflowPipelineOptions$StagingLocationFactory create INFO: No stagingLocation provided, falling back to gcpTempLocation Jun 25, 2021 12:13:52 PM org.apache.beam.runners.dataflow.DataflowRunner fromOptions INFO: PipelineOptions.filesToStage was not specified. Defaulting to files from the classpath: will stage 190 files. Enable logging at DEBUG level to see which files will be staged. Jun 25, 2021 12:13:53 PM org.apache.beam.runners.dataflow.DataflowRunner run INFO: Executing pipeline on the Dataflow Service, which will have billing implications related to Google Compute Engine usage and other Google Cloud Services. Jun 25, 2021 12:13:57 PM org.apache.beam.runners.dataflow.DataflowRunner run INFO: Staging portable pipeline proto to gs://temp-storage-for-perf-tests/loadtests/staging/ Jun 25, 2021 12:13:57 PM org.apache.beam.runners.dataflow.util.PackageUtil tryStagePackage INFO: Uploading <92498 bytes, hash 4e3e7909aae96b55bc796eb9a7b59e6e22e2d3206fc121553e6e1ac91aa8d3b9> to gs://temp-storage-for-perf-tests/loadtests/staging/pipeline-Tj55Carpa1W8eW65p7WebiLi0yBvwSFVPm4ayRqo07k.pb Jun 25, 2021 12:13:59 PM org.apache.beam.runners.dataflow.util.PackageUtil stageClasspathElements INFO: Uploading 190 files from PipelineOptions.filesToStage to staging location to prepare for execution. Jun 25, 2021 12:14:01 PM org.apache.beam.runners.dataflow.util.PackageUtil stageClasspathElements INFO: Staging files complete: 190 files cached, 0 files newly uploaded in 1 seconds Jun 25, 2021 12:14:01 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Read input/DataflowRunner.StreamingUnboundedRead.ReadWithIds as step s1 Jun 25, 2021 12:14:01 PM org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource split INFO: Split into 20 bundles of sizes: [org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@cb7fa71, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@3dffc764, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@4b6e1c0, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@561b61ed, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@654c7d2d, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@26cb5207, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@15400fff, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@18d910b3, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@1e7ab390, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@625d9132, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@77774571, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@277b8fa4, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@6cd64ee8, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@620c8641, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@2f1d0bbc, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@5460b754, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@a9f023e, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@c27a3a2, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@4b200971, org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource@1a2bcd56] Jun 25, 2021 12:14:01 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Read input/StripIds as step s2 Jun 25, 2021 12:14:01 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Collect start time metrics as step s3 Jun 25, 2021 12:14:01 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Total bytes monitor as step s4 Jun 25, 2021 12:14:01 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Window.Into()/Window.Assign as step s5 Jun 25, 2021 12:14:01 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Group by key (0) as step s6 Jun 25, 2021 12:14:01 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Ungroup and reiterate (0) as step s7 Jun 25, 2021 12:14:01 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Collect end time metrics (0) as step s8 Jun 25, 2021 12:14:01 PM org.apache.beam.runners.dataflow.DataflowRunner run INFO: Dataflow SDK version: 2.32.0-SNAPSHOT Jun 25, 2021 12:14:02 PM org.apache.beam.runners.dataflow.DataflowRunner run INFO: To access the Dataflow monitoring console, please navigate to https://console.cloud.google.com/dataflow/jobs/us-central1/2021-06-25_05_14_01-16531951116575768170?project=apache-beam-testing Jun 25, 2021 12:14:02 PM org.apache.beam.runners.dataflow.DataflowRunner run INFO: Submitted job: 2021-06-25_05_14_01-16531951116575768170 Jun 25, 2021 12:14:02 PM org.apache.beam.runners.dataflow.DataflowRunner run INFO: To cancel the job using the 'gcloud' tool, run: > gcloud dataflow jobs --project=apache-beam-testing cancel > --region=us-central1 2021-06-25_05_14_01-16531951116575768170 Jun 25, 2021 12:14:07 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process WARNING: 2021-06-25T12:14:06.557Z: The workflow name is not a valid Cloud Label. Labels applied to Cloud resources (such as GCE Instances) for monitoring will be labeled with this modified job name: load0tests0java110dataflow0v20streaming0gbk01-jenkins-0625-fxzb. For the best monitoring experience, please name your job with a valid Cloud Label. For details, see: https://cloud.google.com/compute/docs/labeling-resources#restrictions Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:09.943Z: Worker configuration: n1-standard-2 in us-central1-f. Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:10.524Z: Expanding SplittableParDo operations into optimizable parts. Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:10.552Z: Expanding CollectionToSingleton operations into optimizable parts. Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:10.624Z: Expanding CoGroupByKey operations into optimizable parts. Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:10.700Z: Expanding SplittableProcessKeyed operations into optimizable parts. Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:10.730Z: Expanding GroupByKey operations into streaming Read/Write steps Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:10.795Z: Lifting ValueCombiningMappingFns into MergeBucketsMappingFns Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:10.899Z: Fusing adjacent ParDo, Read, Write, and Flatten operations Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:10.978Z: Fusing consumer Read input/ParDo(OutputSingleSource)/ParMultiDo(OutputSingleSource) into Read input/Impulse Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:11.008Z: Fusing consumer Read-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/PairWithRestriction into Read input/ParDo(OutputSingleSource)/ParMultiDo(OutputSingleSource) Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:11.038Z: Fusing consumer Read-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/SplitWithSizing into Read-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/PairWithRestriction Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:11.079Z: Fusing consumer Read input/ParDo(StripIds)/ParMultiDo(StripIds) into Read-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/ProcessElementAndRestrictionWithSizing Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:11.113Z: Fusing consumer Collect start time metrics/ParMultiDo(TimeMonitor) into Read input/ParDo(StripIds)/ParMultiDo(StripIds) Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:11.150Z: Fusing consumer Total bytes monitor/ParMultiDo(ByteMonitor) into Collect start time metrics/ParMultiDo(TimeMonitor) Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:11.174Z: Fusing consumer Window.Into()/Window.Assign into Total bytes monitor/ParMultiDo(ByteMonitor) Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:11.260Z: Fusing consumer Group by key (0)/WriteStream into Window.Into()/Window.Assign Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:11.284Z: Fusing consumer Group by key (0)/MergeBuckets into Group by key (0)/ReadStream Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:11.311Z: Fusing consumer Ungroup and reiterate (0)/ParMultiDo(UngroupAndReiterate) into Group by key (0)/MergeBuckets Jun 25, 2021 12:14:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:11.349Z: Fusing consumer Collect end time metrics (0)/ParMultiDo(TimeMonitor) into Ungroup and reiterate (0)/ParMultiDo(UngroupAndReiterate) Jun 25, 2021 12:14:13 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:11.691Z: Starting 5 ****s in us-central1-f... Jun 25, 2021 12:14:17 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:17.524Z: 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 Jun 25, 2021 12:14:57 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:56.132Z: Autoscaling: Raised the number of ****s to 4 so that the pipeline can catch up with its backlog and keep up with its input rate. Jun 25, 2021 12:14:57 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:14:56.162Z: Resized **** pool to 4, though goal was 5. This could be a quota issue. Jun 25, 2021 12:15:09 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:15:06.549Z: 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. Jun 25, 2021 12:15:38 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:15:37.093Z: Workers have started successfully. Jun 25, 2021 12:15:38 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T12:15:37.156Z: Workers have started successfully. Jun 25, 2021 1:29:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T13:29:02.148Z: 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. Jun 25, 2021 2:29:07 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T14:29:05.714Z: 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. Jun 25, 2021 2:49:00 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T14:48:59.146Z: 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. Jun 25, 2021 3:50:11 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T15:50:10.131Z: 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. Jun 25, 2021 4:00:34 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T16:00:34.383Z: Cancel request is committed for workflow job: 2021-06-25_05_14_01-16531951116575768170. Jun 25, 2021 4:00:34 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T16:00:34.495Z: Cleaning up. Jun 25, 2021 4:00:34 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T16:00:34.681Z: Stopping **** pool... Jun 25, 2021 4:00:34 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T16:00:34.791Z: Stopping **** pool... Jun 25, 2021 4:00:42 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process SEVERE: 2021-06-25T16:00:39.896Z: generic::internal: Current work item is no longer valid on backend. The work has already completed or will be retried. This is expected during autoscaling events. generic::internal: Current work item is no longer valid on backend. The work has already completed or will be retried. This is expected during autoscaling events. generic::internal: Current work item is no longer valid on backend. The work has already completed or will be retried. This is expected during autoscaling events. Jun 25, 2021 4:00:44 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process SEVERE: 2021-06-25T16:00:43.294Z: generic::internal: Current work item is no longer valid on backend. The work has already completed or will be retried. This is expected during autoscaling events. Jun 25, 2021 4:01:40 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T16:01:37.644Z: 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. Jun 25, 2021 4:01:40 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2021-06-25T16:01:37.681Z: Worker pool stopped. Jun 25, 2021 4:01:44 PM org.apache.beam.runners.dataflow.DataflowPipelineJob logTerminalState INFO: Job 2021-06-25_05_14_01-16531951116575768170 finished with status CANCELLED. Load test results for test (ID): 29ca81b2-4c51-46a9-a096-2257552d3ec0 and timestamp: 2021-06-25T12:13:53.309000000Z: Metric: Value: dataflow_v2_java11_runtime_sec 13457.938 dataflow_v2_java11_total_bytes_count 3.773274299E10 Exception in thread "main" java.lang.RuntimeException: Invalid job state: CANCELLED. at org.apache.beam.sdk.loadtests.JobFailure.handleFailure(JobFailure.java:55) at org.apache.beam.sdk.loadtests.LoadTest.run(LoadTest.java:141) at org.apache.beam.sdk.loadtests.GroupByKeyLoadTest.run(GroupByKeyLoadTest.java:57) at org.apache.beam.sdk.loadtests.GroupByKeyLoadTest.main(GroupByKeyLoadTest.java:131) > Task :sdks:java:testing:load-tests:run FAILED > Task :runners:google-cloud-dataflow-java:cleanUpDockerImages Untagged: us.gcr.io/apache-beam-testing/java-postcommit-it/java:20210625120630 Untagged: us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:3b014a9014ba2b288ceefb7ba8b9edafa86b6b92869b9df181e9a9b32de27451 Digests: - us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:3b014a9014ba2b288ceefb7ba8b9edafa86b6b92869b9df181e9a9b32de27451 Associated tags: - 20210625120630 Tags: - us.gcr.io/apache-beam-testing/java-postcommit-it/java:20210625120630 Deleted [us.gcr.io/apache-beam-testing/java-postcommit-it/java:20210625120630]. Deleted [us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:3b014a9014ba2b288ceefb7ba8b9edafa86b6b92869b9df181e9a9b32de27451]. 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 7.0. Use '--warning-mode all' to show the individual deprecation warnings. See https://docs.gradle.org/6.8.3/userguide/command_line_interface.html#sec:command_line_warnings BUILD FAILED in 3h 55m 36s 104 actionable tasks: 74 executed, 28 from cache, 2 up-to-date Publishing build scan... https://gradle.com/s/q2ptfrdvxbbme 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]
