See <https://ci-beam.apache.org/job/beam_LoadTests_Java_GBK_Dataflow_V2_Streaming_Java11/780/display/redirect>
Changes: ------------------------------------------ [...truncated 185.89 KB...] #18 [14/16] COPY target/go-licenses/* /opt/apache/beam/third_party_licenses/golang/ #18 DONE 0.1s #19 [15/16] RUN if [ "true" = "false" ] ; then rm -rf /opt/apache/beam/third_party_licenses ; fi #19 DONE 0.3s #20 [16/16] COPY target/profiler/* /opt/google_cloud_profiler/ #20 DONE 0.1s #21 exporting to image #21 exporting layers #21 exporting layers 0.2s done #21 writing image sha256:51986d445cf0f492796204fc38c06f1c395c231d6f5e23d84b563fff8178675e done #21 naming to docker.io/apache/beam_java11_sdk:2.50.0.dev done #21 DONE 0.2s > Task :runners:google-cloud-dataflow-java:buildAndPushDockerJavaContainer 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] 0166e52795c8: Preparing 5f70bf18a086: Preparing 8d8c644d29fd: Preparing 3a5b835329e7: Preparing 10161511be69: Preparing a0015fed177c: Preparing 2d923c0e2621: Preparing 414cd8c74641: Preparing be68f32464c1: Preparing 229f0afac257: Preparing c4429bf28544: Preparing abae19986529: Preparing f40f55564d6c: Preparing 0ee40f8e9926: Preparing 036ec0024e38: Preparing a0015fed177c: Waiting 51b2a6eeeaae: Preparing be68f32464c1: Waiting 77819bbe05ed: Preparing 414cd8c74641: Waiting 16620e049a0a: Preparing 2d923c0e2621: Waiting 229f0afac257: Waiting abae19986529: Waiting c4429bf28544: Waiting f40f55564d6c: Waiting 410573e54541: Preparing 59c56aee1fb4: Preparing 51b2a6eeeaae: Waiting 036ec0024e38: Waiting 410573e54541: Waiting 77819bbe05ed: Waiting 59c56aee1fb4: Waiting 16620e049a0a: Waiting 0ee40f8e9926: Waiting 5f70bf18a086: Layer already exists 8d8c644d29fd: Pushed 3a5b835329e7: Pushed a0015fed177c: Pushed 0166e52795c8: Pushed 10161511be69: Pushed 2d923c0e2621: Pushed be68f32464c1: Pushed 229f0afac257: Pushed c4429bf28544: Pushed 414cd8c74641: Pushed 51b2a6eeeaae: Layer already exists 77819bbe05ed: Layer already exists 16620e049a0a: Layer already exists abae19986529: Pushed 410573e54541: Layer already exists 59c56aee1fb4: Layer already exists f40f55564d6c: Pushed 0ee40f8e9926: Pushed 036ec0024e38: Pushed 20230809151619: digest: sha256:7b5436cd9089ec62c2f26410ef93a7011772c80e2677e588d2b9b11309348ea2 size: 4505 > Task :sdks:java:testing:load-tests:run Aug 09, 2023 3:16:39 PM org.apache.beam.runners.dataflow.options.DataflowPipelineOptions$StagingLocationFactory create INFO: No stagingLocation provided, falling back to gcpTempLocation Aug 09, 2023 3:16:40 PM org.apache.beam.runners.dataflow.DataflowRunner fromOptions INFO: PipelineOptions.filesToStage was not specified. Defaulting to files from the classpath: will stage 234 files. Enable logging at DEBUG level to see which files will be staged. Aug 09, 2023 3:16:41 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. Aug 09, 2023 3:16:43 PM org.apache.beam.runners.dataflow.util.PackageUtil stageClasspathElements INFO: Uploading 234 files from PipelineOptions.filesToStage to staging location to prepare for execution. Aug 09, 2023 3:16:43 PM org.apache.beam.runners.dataflow.util.PackageUtil stageClasspathElements INFO: Staging files complete: 234 files cached, 0 files newly uploaded in 0 seconds Aug 09, 2023 3:16:43 PM org.apache.beam.runners.dataflow.DataflowRunner run INFO: Staging portable pipeline proto to gs://temp-storage-for-perf-tests/loadtests/staging/ Aug 09, 2023 3:16:44 PM org.apache.beam.runners.dataflow.util.PackageUtil tryStagePackage INFO: Uploading <102579 bytes, hash a1b40622ed08102272f890a7515ce7ff0c21d9491826ed95bd1786c7fdacabe0> to gs://temp-storage-for-perf-tests/loadtests/staging/pipeline-obQGIu0IECJy-JCnUVzn_wwh2UkYJu2VvReGx_2sq-A.pb Aug 09, 2023 3:16:45 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Read input/DataflowRunner.StreamingUnboundedRead.ReadWithIds as step s1 Aug 09, 2023 3:16:45 PM org.apache.beam.sdk.io.synthetic.SyntheticUnboundedSource split INFO: Split into 20 bundles: [SyntheticUnboundedSource{startOffset=0, endOffset=1000}, SyntheticUnboundedSource{startOffset=1000, endOffset=2000}, SyntheticUnboundedSource{startOffset=2000, endOffset=3000}, SyntheticUnboundedSource{startOffset=3000, endOffset=4000}, SyntheticUnboundedSource{startOffset=4000, endOffset=5000}, SyntheticUnboundedSource{startOffset=5000, endOffset=6000}, SyntheticUnboundedSource{startOffset=6000, endOffset=7000}, SyntheticUnboundedSource{startOffset=7000, endOffset=8000}, SyntheticUnboundedSource{startOffset=8000, endOffset=9000}, SyntheticUnboundedSource{startOffset=9000, endOffset=10000}, SyntheticUnboundedSource{startOffset=10000, endOffset=11000}, SyntheticUnboundedSource{startOffset=11000, endOffset=12000}, SyntheticUnboundedSource{startOffset=12000, endOffset=13000}, SyntheticUnboundedSource{startOffset=13000, endOffset=14000}, SyntheticUnboundedSource{startOffset=14000, endOffset=15000}, SyntheticUnboundedSource{startOffset=15000, endOffset=16000}, SyntheticUnboundedSource{startOffset=16000, endOffset=17000}, SyntheticUnboundedSource{startOffset=17000, endOffset=18000}, SyntheticUnboundedSource{startOffset=18000, endOffset=19000}, SyntheticUnboundedSource{startOffset=19000, endOffset=20000}] Aug 09, 2023 3:16:45 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Read input/StripIds as step s2 Aug 09, 2023 3:16:45 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Collect start time metrics as step s3 Aug 09, 2023 3:16:45 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Total bytes monitor as step s4 Aug 09, 2023 3:16:45 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Window.Into()/Window.Assign as step s5 Aug 09, 2023 3:16:45 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Group by key (0) as step s6 Aug 09, 2023 3:16:45 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Ungroup and reiterate (0) as step s7 Aug 09, 2023 3:16:45 PM org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep INFO: Adding Collect end time metrics (0) as step s8 Aug 09, 2023 3:16:46 PM org.apache.beam.runners.dataflow.DataflowRunner run INFO: Dataflow SDK version: 2.50.0-SNAPSHOT Aug 09, 2023 3:16:47 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/2023-08-09_08_16_46-2343108208797246363?project=apache-beam-testing Aug 09, 2023 3:16:47 PM org.apache.beam.runners.dataflow.DataflowRunner run INFO: Submitted job: 2023-08-09_08_16_46-2343108208797246363 Aug 09, 2023 3:16:47 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 2023-08-09_08_16_46-2343108208797246363 Aug 09, 2023 3:16:56 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process WARNING: 2023-08-09T15:16:51.245Z: 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: load0tests0java110dataflow0v20streaming0gbk03-jenkins-0809-prhr. 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 Aug 09, 2023 3:17:01 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:00.187Z: Worker configuration: e2-standard-2 in us-central1-a. Aug 09, 2023 3:17:01 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:01.709Z: Expanding SplittableParDo operations into optimizable parts. Aug 09, 2023 3:17:01 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:01.730Z: Expanding CollectionToSingleton operations into optimizable parts. Aug 09, 2023 3:17:01 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:01.772Z: Expanding CoGroupByKey operations into optimizable parts. Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:01.820Z: Expanding SplittableProcessKeyed operations into optimizable parts. Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:01.838Z: Expanding GroupByKey operations into streaming Read/Write steps Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:01.882Z: Lifting ValueCombiningMappingFns into MergeBucketsMappingFns Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:01.953Z: Fusing adjacent ParDo, Read, Write, and Flatten operations Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:01.973Z: Fusing consumer Read input/ParDo(OutputSingleSource)/ParMultiDo(OutputSingleSource) into Read input/Impulse Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:01.990Z: Fusing consumer Read-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/PairWithRestriction into Read input/ParDo(OutputSingleSource)/ParMultiDo(OutputSingleSource) Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:02.009Z: Fusing consumer Read-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/SplitWithSizing into Read-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/PairWithRestriction Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:02.027Z: Fusing consumer Read input/ParDo(StripIds)/ParMultiDo(StripIds) into Read-input-ParDo-UnboundedSourceAsSDFWrapper--ParMultiDo-UnboundedSourceAsSDFWrapper-/ProcessElementAndRestrictionWithSizing Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:02.044Z: Fusing consumer Collect start time metrics/ParMultiDo(TimeMonitor) into Read input/ParDo(StripIds)/ParMultiDo(StripIds) Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:02.063Z: Fusing consumer Total bytes monitor/ParMultiDo(ByteMonitor) into Collect start time metrics/ParMultiDo(TimeMonitor) Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:02.082Z: Fusing consumer Window.Into()/Window.Assign into Total bytes monitor/ParMultiDo(ByteMonitor) Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:02.107Z: Fusing consumer Group by key (0)/WriteStream into Window.Into()/Window.Assign Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:02.125Z: Fusing consumer Group by key (0)/MergeBuckets into Group by key (0)/ReadStream Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:02.144Z: Fusing consumer Ungroup and reiterate (0)/ParMultiDo(UngroupAndReiterate) into Group by key (0)/MergeBuckets Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:02.161Z: Fusing consumer Collect end time metrics (0)/ParMultiDo(TimeMonitor) into Ungroup and reiterate (0)/ParMultiDo(UngroupAndReiterate) Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:02.263Z: Running job using Streaming Engine Aug 09, 2023 3:17:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:02.506Z: Starting 5 ****s in us-central1-a... Aug 09, 2023 3:17:23 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:23.068Z: 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 Aug 09, 2023 3:17:55 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:53.671Z: Autoscaling: Raised the number of ****s to 2 so that the pipeline can catch up with its backlog and keep up with its input rate. Aug 09, 2023 3:17:55 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:17:53.697Z: Autoscaling: Resized **** pool to 2, though goal was 5. This could be a quota issue. Aug 09, 2023 3:18:03 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:18:03.580Z: 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. Aug 09, 2023 3:18:46 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:18:46.098Z: Workers have started successfully. Aug 09, 2023 3:18:46 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:18:46.427Z: All ****s have finished the startup processes and began to receive work requests. Aug 09, 2023 3:21:21 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:21:19.531Z: Cleaning up. Aug 09, 2023 3:21:21 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:21:19.622Z: Stopping **** pool... Aug 09, 2023 3:21:21 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:21:19.670Z: Stopping **** pool... Aug 09, 2023 3:23:42 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:23:41.775Z: 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. Aug 09, 2023 3:23:42 PM org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process INFO: 2023-08-09T15:23:41.839Z: Worker pool stopped. Aug 09, 2023 3:28:48 PM org.apache.beam.runners.dataflow.DataflowPipelineJob logTerminalState INFO: Job 2023-08-09_08_16_46-2343108208797246363 finished with status DONE. Aug 09, 2023 3:28:48 PM org.apache.beam.sdk.testutils.metrics.MetricsReader getCounterMetric SEVERE: Failed to get metric totalBytes.count, from namespace gbk Load test results for test (ID): 64873c9a-f01a-4683-a65e-051f1869310b and timestamp: 2023-08-09T15:16:40.671000000Z: Metric: Value: dataflow_v2_java11_runtime_sec 0.0 dataflow_v2_java11_total_bytes_count -1.0 Exception in thread "main" java.lang.RuntimeException: Invalid test results 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.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:cleanUpDockerJavaImages Untagged: us.gcr.io/apache-beam-testing/java-postcommit-it/java:20230809151619 Untagged: us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:7b5436cd9089ec62c2f26410ef93a7011772c80e2677e588d2b9b11309348ea2 WARNING: Successfully resolved tag to sha256, but it is recommended to use sha256 directly. Tag: [us.gcr.io/apache-beam-testing/java-postcommit-it/java:20230809151619] - referencing digest: [us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:7b5436cd9089ec62c2f26410ef93a7011772c80e2677e588d2b9b11309348ea2] Deleted [[us.gcr.io/apache-beam-testing/java-postcommit-it/java:20230809151619] (referencing [us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:7b5436cd9089ec62c2f26410ef93a7011772c80e2677e588d2b9b11309348ea2])]. Removing untagged image us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:7b5436cd9089ec62c2f26410ef93a7011772c80e2677e588d2b9b11309348ea2 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. * 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.6.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 12m 47s 112 actionable tasks: 9 executed, 103 up-to-date Publishing build scan... https://ge.apache.org/s/kl6yfu537oyvo 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]
