You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@beam.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2023/04/25 14:59:33 UTC

Build failed in Jenkins: beam_python_mongoio_load_test #4761

See <https://ci-beam.apache.org/job/beam_python_mongoio_load_test/4761/display/redirect?page=changes>

Changes:

[noreply] [AWS2] Bump AWS v2 SDK (closes #26323) (#26380)


------------------------------------------
[...truncated 54.64 KB...]
 location: 'us-central1'
 name: 'beamapp-jenkins-0425144600-400341-2eavqiqt'
 projectId: 'apache-beam-testing'
 stageStates: []
 startTime: '2023-04-25T14:46:01.939067Z'
 steps: []
 tempFiles: []
 type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)>
INFO:apache_beam.runners.dataflow.internal.apiclient:Created job with id: [2023-04-25_07_46_01-4160590049402753909]
INFO:apache_beam.runners.dataflow.internal.apiclient:Submitted job: 2023-04-25_07_46_01-4160590049402753909
INFO:apache_beam.runners.dataflow.internal.apiclient:To access the Dataflow monitoring console, please navigate to https://console.cloud.google.com/dataflow/jobs/us-central1/2023-04-25_07_46_01-4160590049402753909?project=apache-beam-testing
INFO:apache_beam.runners.dataflow.dataflow_runner:Job 2023-04-25_07_46_01-4160590049402753909 is in state JOB_STATE_PENDING
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:01.397Z: JOB_MESSAGE_BASIC: Dataflow Runner V2 auto-enabled. Use --experiments=disable_runner_v2 to opt out.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:03.043Z: JOB_MESSAGE_DETAILED: Autoscaling is enabled for job 2023-04-25_07_46_01-4160590049402753909. The number of workers will be between 1 and 1000.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:03.140Z: JOB_MESSAGE_DETAILED: Autoscaling was automatically enabled for job 2023-04-25_07_46_01-4160590049402753909.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:05.127Z: JOB_MESSAGE_BASIC: Worker configuration: e2-standard-2 in us-central1-b.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:05.958Z: JOB_MESSAGE_DETAILED: Expanding SplittableParDo operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:05.983Z: JOB_MESSAGE_DETAILED: Expanding CollectionToSingleton operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.040Z: JOB_MESSAGE_DETAILED: Expanding CoGroupByKey operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.071Z: JOB_MESSAGE_DEBUG: Combiner lifting skipped for step WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey: GroupByKey not followed by a combiner.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.099Z: JOB_MESSAGE_DETAILED: Expanding GroupByKey operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.126Z: JOB_MESSAGE_DEBUG: Annotating graph with Autotuner information.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.165Z: JOB_MESSAGE_DETAILED: Fusing adjacent ParDo, Read, Write, and Flatten operations
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.191Z: JOB_MESSAGE_DETAILED: Fusing consumer Create/FlatMap(<lambda at core.py:3574>) into Create/Impulse
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.225Z: JOB_MESSAGE_DETAILED: Fusing consumer Create/Map(decode) into Create/FlatMap(<lambda at core.py:3574>)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.260Z: JOB_MESSAGE_DETAILED: Fusing consumer Create documents into Create/Map(decode)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.284Z: JOB_MESSAGE_DETAILED: Fusing consumer WriteToMongoDB/ParDo(_GenerateObjectIdFn) into Create documents
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.309Z: JOB_MESSAGE_DETAILED: Fusing consumer WriteToMongoDB/Reshuffle/AddRandomKeys into WriteToMongoDB/ParDo(_GenerateObjectIdFn)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.337Z: JOB_MESSAGE_DETAILED: Fusing consumer WriteToMongoDB/Reshuffle/ReshufflePerKey/Map(reify_timestamps) into WriteToMongoDB/Reshuffle/AddRandomKeys
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.369Z: JOB_MESSAGE_DETAILED: Fusing consumer WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Reify into WriteToMongoDB/Reshuffle/ReshufflePerKey/Map(reify_timestamps)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.395Z: JOB_MESSAGE_DETAILED: Fusing consumer WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Write into WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Reify
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.427Z: JOB_MESSAGE_DETAILED: Fusing consumer WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/GroupByWindow into WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Read
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.461Z: JOB_MESSAGE_DETAILED: Fusing consumer WriteToMongoDB/Reshuffle/ReshufflePerKey/FlatMap(restore_timestamps) into WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/GroupByWindow
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.495Z: JOB_MESSAGE_DETAILED: Fusing consumer WriteToMongoDB/Reshuffle/RemoveRandomKeys into WriteToMongoDB/Reshuffle/ReshufflePerKey/FlatMap(restore_timestamps)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.520Z: JOB_MESSAGE_DETAILED: Fusing consumer WriteToMongoDB/ParDo(_WriteMongoFn) into WriteToMongoDB/Reshuffle/RemoveRandomKeys
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.557Z: JOB_MESSAGE_DEBUG: Workflow config is missing a default resource spec.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.583Z: JOB_MESSAGE_DEBUG: Adding StepResource setup and teardown to workflow graph.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.616Z: JOB_MESSAGE_DEBUG: Adding workflow start and stop steps.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.642Z: JOB_MESSAGE_DEBUG: Assigning stage ids.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.769Z: JOB_MESSAGE_DEBUG: Executing wait step start13
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.817Z: JOB_MESSAGE_BASIC: Executing operation WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Create
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.852Z: JOB_MESSAGE_DEBUG: Starting worker pool setup.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:06.882Z: JOB_MESSAGE_BASIC: Starting 5 workers in us-central1-b...
INFO:apache_beam.runners.dataflow.dataflow_runner:Job 2023-04-25_07_46_01-4160590049402753909 is in state JOB_STATE_RUNNING
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:07.262Z: JOB_MESSAGE_BASIC: Finished operation WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Create
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:07.318Z: JOB_MESSAGE_DEBUG: Value "WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Session" materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:07.377Z: JOB_MESSAGE_BASIC: Executing operation Create/Impulse+Create/FlatMap(<lambda at core.py:3574>)+Create/Map(decode)+Create documents+WriteToMongoDB/ParDo(_GenerateObjectIdFn)+WriteToMongoDB/Reshuffle/AddRandomKeys+WriteToMongoDB/Reshuffle/ReshufflePerKey/Map(reify_timestamps)+WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Reify+WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Write
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:25.539Z: JOB_MESSAGE_BASIC: 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
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:50.738Z: JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of workers to 4 based on the rate of progress in the currently running stage(s).
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:46:50.759Z: JOB_MESSAGE_DETAILED: Autoscaling: Resized worker pool to 4, though goal was 5.  This could be a quota issue.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:47:00.525Z: JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of workers to 5 based on the rate of progress in the currently running stage(s).
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:47:00.553Z: JOB_MESSAGE_DETAILED: Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:47:21.086Z: JOB_MESSAGE_DETAILED: Workers have started successfully.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:49:37.797Z: JOB_MESSAGE_DETAILED: Autoscaling: Reduced the number of workers to 1 based on the rate of progress in the currently running stage(s).
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:49:37.823Z: JOB_MESSAGE_DETAILED: Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:49:42.859Z: JOB_MESSAGE_DETAILED: Autoscaling: Resizing worker pool from 5 to 1.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:52:58.878Z: JOB_MESSAGE_DETAILED: All workers have finished the startup processes and began to receive work requests.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:53:37.980Z: JOB_MESSAGE_BASIC: Finished operation Create/Impulse+Create/FlatMap(<lambda at core.py:3574>)+Create/Map(decode)+Create documents+WriteToMongoDB/ParDo(_GenerateObjectIdFn)+WriteToMongoDB/Reshuffle/AddRandomKeys+WriteToMongoDB/Reshuffle/ReshufflePerKey/Map(reify_timestamps)+WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Reify+WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Write
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:53:38.031Z: JOB_MESSAGE_BASIC: Executing operation WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Close
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:53:38.930Z: JOB_MESSAGE_BASIC: Finished operation WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Close
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:53:38.997Z: JOB_MESSAGE_BASIC: Executing operation WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Read+WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/GroupByWindow+WriteToMongoDB/Reshuffle/ReshufflePerKey/FlatMap(restore_timestamps)+WriteToMongoDB/Reshuffle/RemoveRandomKeys+WriteToMongoDB/ParDo(_WriteMongoFn)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:55:08.690Z: JOB_MESSAGE_BASIC: Finished operation WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/Read+WriteToMongoDB/Reshuffle/ReshufflePerKey/GroupByKey/GroupByWindow+WriteToMongoDB/Reshuffle/ReshufflePerKey/FlatMap(restore_timestamps)+WriteToMongoDB/Reshuffle/RemoveRandomKeys+WriteToMongoDB/ParDo(_WriteMongoFn)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:55:08.733Z: JOB_MESSAGE_DEBUG: Executing success step success11
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:55:08.796Z: JOB_MESSAGE_DETAILED: Cleaning up.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:55:08.839Z: JOB_MESSAGE_DEBUG: Starting worker pool teardown.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:55:08.875Z: JOB_MESSAGE_BASIC: Stopping worker pool...
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:20.809Z: JOB_MESSAGE_DETAILED: Autoscaling: Resized worker pool from 1 to 0.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:20.874Z: JOB_MESSAGE_BASIC: Worker pool stopped.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:20.893Z: JOB_MESSAGE_DEBUG: Tearing down pending resources...
INFO:apache_beam.runners.dataflow.dataflow_runner:Job 2023-04-25_07_46_01-4160590049402753909 is in state JOB_STATE_DONE
INFO:__main__:Writing 1000000 documents to mongodb finished in 692.715 seconds
INFO:__main__:================================================================================
INFO:__main__:Reading from mongodb beam_mongodbio_it_db:integration_test_1682433959
INFO:__main__:reader params   : {'projection': ['number']}
INFO:__main__:expected results: {'number_sum': 499999500000, 'docs_count': 1000000}
<https://ci-beam.apache.org/job/beam_python_mongoio_load_test/ws/src/build/gradleenv/-1734967052/lib/python3.7/site-packages/apache_beam/io/mongodbio_it_test.py>:153: FutureWarning: ReadFromMongoDB is experimental.
  | 'Map' >> beam.Map(lambda doc: doc['number']))
INFO:apache_beam.runners.portability.stager:Copying Beam SDK "<https://ci-beam.apache.org/job/beam_python_mongoio_load_test/ws/src/sdks/python/build/apache-beam.tar.gz"> to staging location.
INFO:root:Default Python SDK image for environment is apache/beam_python3.7_sdk:2.48.0.dev
INFO:root:Using provided Python SDK container image: gcr.io/cloud-dataflow/v1beta3/python37:beam-master-20230412
INFO:root:Python SDK container image set to "gcr.io/cloud-dataflow/v1beta3/python37:beam-master-20230412" for Docker environment
INFO:apache_beam.runners.portability.fn_api_runner.translations:==================== <function pack_combiners at 0x7f530e8498c0> ====================
INFO:apache_beam.runners.portability.fn_api_runner.translations:==================== <function sort_stages at 0x7f530e84a0e0> ====================
INFO:apache_beam.runners.dataflow.internal.apiclient:Defaulting to the temp_location as staging_location: gs://temp-storage-for-perf-tests/loadtests
INFO:apache_beam.runners.dataflow.internal.apiclient:Starting GCS upload to gs://temp-storage-for-perf-tests/loadtests/beamapp-jenkins-0425145734-004759-n1ymcj2q.1682434654.005116/dataflow_python_sdk.tar...
INFO:apache_beam.runners.dataflow.internal.apiclient:Completed GCS upload to gs://temp-storage-for-perf-tests/loadtests/beamapp-jenkins-0425145734-004759-n1ymcj2q.1682434654.005116/dataflow_python_sdk.tar in 0 seconds.
INFO:apache_beam.runners.dataflow.internal.apiclient:Starting GCS upload to gs://temp-storage-for-perf-tests/loadtests/beamapp-jenkins-0425145734-004759-n1ymcj2q.1682434654.005116/pipeline.pb...
INFO:apache_beam.runners.dataflow.internal.apiclient:Completed GCS upload to gs://temp-storage-for-perf-tests/loadtests/beamapp-jenkins-0425145734-004759-n1ymcj2q.1682434654.005116/pipeline.pb in 0 seconds.
INFO:apache_beam.runners.dataflow.internal.apiclient:Create job: <Job
 clientRequestId: '20230425145734006049-2263'
 createTime: '2023-04-25T14:57:35.648640Z'
 currentStateTime: '1970-01-01T00:00:00Z'
 id: '2023-04-25_07_57_34-9602294569630458528'
 location: 'us-central1'
 name: 'beamapp-jenkins-0425145734-004759-n1ymcj2q'
 projectId: 'apache-beam-testing'
 stageStates: []
 startTime: '2023-04-25T14:57:35.648640Z'
 steps: []
 tempFiles: []
 type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)>
INFO:apache_beam.runners.dataflow.internal.apiclient:Created job with id: [2023-04-25_07_57_34-9602294569630458528]
INFO:apache_beam.runners.dataflow.internal.apiclient:Submitted job: 2023-04-25_07_57_34-9602294569630458528
INFO:apache_beam.runners.dataflow.internal.apiclient:To access the Dataflow monitoring console, please navigate to https://console.cloud.google.com/dataflow/jobs/us-central1/2023-04-25_07_57_34-9602294569630458528?project=apache-beam-testing
INFO:apache_beam.runners.dataflow.dataflow_runner:Job 2023-04-25_07_57_34-9602294569630458528 is in state JOB_STATE_PENDING
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:35.076Z: JOB_MESSAGE_BASIC: Dataflow Runner V2 auto-enabled. Use --experiments=disable_runner_v2 to opt out.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:36.288Z: JOB_MESSAGE_DETAILED: Autoscaling is enabled for job 2023-04-25_07_57_34-9602294569630458528. The number of workers will be between 1 and 1000.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:36.624Z: JOB_MESSAGE_DETAILED: Autoscaling was automatically enabled for job 2023-04-25_07_57_34-9602294569630458528.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:38.392Z: JOB_MESSAGE_BASIC: Worker configuration: e2-standard-2 in us-central1-f.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.386Z: JOB_MESSAGE_DETAILED: Expanding SplittableParDo operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.420Z: JOB_MESSAGE_DETAILED: Expanding CollectionToSingleton operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.476Z: JOB_MESSAGE_DETAILED: Expanding CoGroupByKey operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.498Z: JOB_MESSAGE_DEBUG: Combiner lifting skipped for step assert_that/Group/CoGroupByKeyImpl/GroupByKey: GroupByKey not followed by a combiner.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.601Z: JOB_MESSAGE_DETAILED: Expanding GroupByKey operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.630Z: JOB_MESSAGE_DEBUG: Annotating graph with Autotuner information.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.661Z: JOB_MESSAGE_DETAILED: Fusing adjacent ParDo, Read, Write, and Flatten operations
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.697Z: JOB_MESSAGE_DEBUG: Inserted coder converter before flatten ref_AppliedPTransform_Flatten_36
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.721Z: JOB_MESSAGE_DEBUG: Inserted coder converter after flatten ref_AppliedPTransform_assert_that-Group-CoGroupByKeyImpl-Flatten_49
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.753Z: JOB_MESSAGE_DETAILED: Unzipping flatten ref_AppliedPTransform_assert_that-Group-CoGroupByKeyImpl-Flatten_49 for input ref_AppliedPTransform_assert_that-Group-CoGroupByKeyImpl-Tag-0-_47.None
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.786Z: JOB_MESSAGE_DETAILED: Fusing unzipped copy of assert_that/Group/CoGroupByKeyImpl/Flatten/OutputIdentity, through flatten assert_that/Group/CoGroupByKeyImpl/Flatten, into producer assert_that/Group/CoGroupByKeyImpl/Tag[0]
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.819Z: JOB_MESSAGE_DETAILED: Fusing consumer assert_that/Group/CoGroupByKeyImpl/MapTuple(collect_values) into assert_that/Group/CoGroupByKeyImpl/GroupByKey/Read
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.853Z: JOB_MESSAGE_DETAILED: Fusing consumer assert_that/Group/RestoreTags into assert_that/Group/CoGroupByKeyImpl/MapTuple(collect_values)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.874Z: JOB_MESSAGE_DETAILED: Fusing consumer assert_that/Unkey into assert_that/Group/RestoreTags
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.900Z: JOB_MESSAGE_DETAILED: Fusing consumer assert_that/Match into assert_that/Unkey
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.927Z: JOB_MESSAGE_DETAILED: Unzipping flatten ref_AppliedPTransform_assert_that-Group-CoGroupByKeyImpl-Flatten_49-u47 for input ref_AppliedPTransform_assert_that-Group-CoGroupByKeyImpl-Flatten_49.None-c45
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.951Z: JOB_MESSAGE_DETAILED: Fusing unzipped copy of assert_that/Group/CoGroupByKeyImpl/GroupByKey/Write, through flatten assert_that/Group/CoGroupByKeyImpl/Flatten/Unzipped-1, into producer assert_that/Group/CoGroupByKeyImpl/Flatten/OutputIdentity
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:39.976Z: JOB_MESSAGE_DETAILED: Fusing consumer assert_that/Group/CoGroupByKeyImpl/Flatten/OutputIdentity into assert_that/Group/CoGroupByKeyImpl/Tag[1]
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.008Z: JOB_MESSAGE_DETAILED: Fusing consumer assert_that/Group/CoGroupByKeyImpl/GroupByKey/Write into assert_that/Group/CoGroupByKeyImpl/Flatten/OutputIdentity
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.043Z: JOB_MESSAGE_DETAILED: Unzipping flatten ref_AppliedPTransform_Flatten_36 for input ref_AppliedPTransform_Combine-InjectDefault_21.None
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.077Z: JOB_MESSAGE_DETAILED: Fusing unzipped copy of assert_that/WindowInto(WindowIntoFn), through flatten Flatten, into producer Combine/InjectDefault
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.098Z: JOB_MESSAGE_DETAILED: Fusing consumer assert_that/Group/CoGroupByKeyImpl/Tag[1] into assert_that/ToVoidKey
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.131Z: JOB_MESSAGE_DETAILED: Unzipping flatten ref_AppliedPTransform_Flatten_36-u54 for input ref_AppliedPTransform_assert_that-WindowInto-WindowIntoFn-_43.None-c52
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.155Z: JOB_MESSAGE_DETAILED: Fusing unzipped copy of assert_that/ToVoidKey, through flatten Flatten/Unzipped-1, into producer assert_that/WindowInto(WindowIntoFn)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.186Z: JOB_MESSAGE_DETAILED: Fusing consumer Combine/KeyWithVoid into Map
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.219Z: JOB_MESSAGE_DETAILED: Fusing consumer Count/CombineGlobally(CountCombineFn)/KeyWithVoid into Map
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.248Z: JOB_MESSAGE_DETAILED: Fusing consumer assert_that/WindowInto(WindowIntoFn) into Flatten/InputIdentity
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.274Z: JOB_MESSAGE_DETAILED: Fusing consumer assert_that/ToVoidKey into assert_that/WindowInto(WindowIntoFn)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.305Z: JOB_MESSAGE_DETAILED: Fusing consumer ReadFromMongoDB/Read/Map(<lambda at iobase.py:908>) into ReadFromMongoDB/Read/Impulse
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.338Z: JOB_MESSAGE_DETAILED: Fusing consumer ref_AppliedPTransform_ReadFromMongoDB-Read-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_7/PairWithRestriction into ReadFromMongoDB/Read/Map(<lambda at iobase.py:908>)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.374Z: JOB_MESSAGE_DETAILED: Fusing consumer ref_AppliedPTransform_ReadFromMongoDB-Read-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_7/SplitWithSizing into ref_AppliedPTransform_ReadFromMongoDB-Read-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_7/PairWithRestriction
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.397Z: JOB_MESSAGE_DETAILED: Fusing consumer Map into ref_AppliedPTransform_ReadFromMongoDB-Read-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_7/ProcessElementAndRestrictionWithSizing
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.427Z: JOB_MESSAGE_DETAILED: Fusing consumer Combine/CombinePerKey/GroupByKey+Combine/CombinePerKey/Combine/Partial into Combine/KeyWithVoid
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.461Z: JOB_MESSAGE_DETAILED: Fusing consumer Combine/CombinePerKey/GroupByKey/Write into Combine/CombinePerKey/GroupByKey+Combine/CombinePerKey/Combine/Partial
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.485Z: JOB_MESSAGE_DETAILED: Fusing consumer Combine/CombinePerKey/Combine into Combine/CombinePerKey/GroupByKey/Read
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.514Z: JOB_MESSAGE_DETAILED: Fusing consumer Combine/CombinePerKey/Combine/Extract into Combine/CombinePerKey/Combine
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.548Z: JOB_MESSAGE_DETAILED: Fusing consumer Combine/UnKey into Combine/CombinePerKey/Combine/Extract
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.579Z: JOB_MESSAGE_DETAILED: Fusing consumer Count/CombineGlobally(CountCombineFn)/CombinePerKey/GroupByKey+Count/CombineGlobally(CountCombineFn)/CombinePerKey/Combine/Partial into Count/CombineGlobally(CountCombineFn)/KeyWithVoid
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.603Z: JOB_MESSAGE_DETAILED: Fusing consumer Count/CombineGlobally(CountCombineFn)/CombinePerKey/GroupByKey/Write into Count/CombineGlobally(CountCombineFn)/CombinePerKey/GroupByKey+Count/CombineGlobally(CountCombineFn)/CombinePerKey/Combine/Partial
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.633Z: JOB_MESSAGE_DETAILED: Fusing consumer Count/CombineGlobally(CountCombineFn)/CombinePerKey/Combine into Count/CombineGlobally(CountCombineFn)/CombinePerKey/GroupByKey/Read
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.666Z: JOB_MESSAGE_DETAILED: Fusing consumer Count/CombineGlobally(CountCombineFn)/CombinePerKey/Combine/Extract into Count/CombineGlobally(CountCombineFn)/CombinePerKey/Combine
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.701Z: JOB_MESSAGE_DETAILED: Fusing consumer Count/CombineGlobally(CountCombineFn)/UnKey into Count/CombineGlobally(CountCombineFn)/CombinePerKey/Combine/Extract
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.726Z: JOB_MESSAGE_DETAILED: Fusing consumer Combine/DoOnce/FlatMap(<lambda at core.py:3574>) into Combine/DoOnce/Impulse
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.755Z: JOB_MESSAGE_DETAILED: Fusing consumer Combine/DoOnce/Map(decode) into Combine/DoOnce/FlatMap(<lambda at core.py:3574>)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.788Z: JOB_MESSAGE_DETAILED: Fusing consumer Combine/InjectDefault into Combine/DoOnce/Map(decode)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.819Z: JOB_MESSAGE_DETAILED: Fusing consumer Count/CombineGlobally(CountCombineFn)/DoOnce/FlatMap(<lambda at core.py:3574>) into Count/CombineGlobally(CountCombineFn)/DoOnce/Impulse
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.849Z: JOB_MESSAGE_DETAILED: Fusing consumer Count/CombineGlobally(CountCombineFn)/DoOnce/Map(decode) into Count/CombineGlobally(CountCombineFn)/DoOnce/FlatMap(<lambda at core.py:3574>)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.876Z: JOB_MESSAGE_DETAILED: Fusing consumer Count/CombineGlobally(CountCombineFn)/InjectDefault into Count/CombineGlobally(CountCombineFn)/DoOnce/Map(decode)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.908Z: JOB_MESSAGE_DETAILED: Fusing consumer Flatten/InputIdentity into Count/CombineGlobally(CountCombineFn)/InjectDefault
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.939Z: JOB_MESSAGE_DETAILED: Fusing consumer assert_that/Create/FlatMap(<lambda at core.py:3574>) into assert_that/Create/Impulse
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:40.970Z: JOB_MESSAGE_DETAILED: Fusing consumer assert_that/Create/Map(decode) into assert_that/Create/FlatMap(<lambda at core.py:3574>)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:41.001Z: JOB_MESSAGE_DETAILED: Fusing consumer assert_that/Group/CoGroupByKeyImpl/Tag[0] into assert_that/Create/Map(decode)
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:41.053Z: JOB_MESSAGE_DEBUG: Workflow config is missing a default resource spec.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:41.073Z: JOB_MESSAGE_DEBUG: Adding StepResource setup and teardown to workflow graph.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:41.101Z: JOB_MESSAGE_DEBUG: Adding workflow start and stop steps.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:41.126Z: JOB_MESSAGE_DEBUG: Assigning stage ids.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:41.256Z: JOB_MESSAGE_DEBUG: Executing wait step start73
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:41.327Z: JOB_MESSAGE_BASIC: Executing operation ReadFromMongoDB/Read/Impulse+ReadFromMongoDB/Read/Map(<lambda at iobase.py:908>)+ref_AppliedPTransform_ReadFromMongoDB-Read-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_7/PairWithRestriction+ref_AppliedPTransform_ReadFromMongoDB-Read-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_7/SplitWithSizing
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:41.371Z: JOB_MESSAGE_DEBUG: Starting worker pool setup.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:41.403Z: JOB_MESSAGE_BASIC: Starting 5 workers in us-central1-f...
INFO:apache_beam.runners.dataflow.dataflow_runner:Job 2023-04-25_07_57_34-9602294569630458528 is in state JOB_STATE_RUNNING
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:57:53.038Z: JOB_MESSAGE_BASIC: 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
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:58:20.305Z: JOB_MESSAGE_WARNING: Autoscaling: Startup of the worker pool in zone us-central1-f reached 4 workers, but the goal was 5 workers. The service will retry. Please refer to https://cloud.google.com/dataflow/docs/guides/common-errors#worker-pool-failure for help troubleshooting. ZONE_RESOURCE_POOL_EXHAUSTED_WITH_DETAILS: Instance 'beamapp-jenkins-042514573-04250757-bib2-harness-ss5k' creation failed: The zone 'projects/apache-beam-testing/zones/us-central1-a' does not have enough resources available to fulfill the request.  '(resource type:compute)'.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:58:30.328Z: JOB_MESSAGE_ERROR: Startup of the worker pool in zone us-central1-f failed to bring up any of the desired 5 workers. Please refer to https://cloud.google.com/dataflow/docs/guides/common-errors#worker-pool-failure for help troubleshooting. ZONE_RESOURCE_POOL_EXHAUSTED_WITH_DETAILS: Instance 'beamapp-jenkins-042514573-04250757-bib2-harness-ss5k' creation failed: The zone 'projects/apache-beam-testing/zones/us-central1-a' does not have enough resources available to fulfill the request.  '(resource type:compute)'.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:58:30.362Z: JOB_MESSAGE_ERROR: Workflow failed.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:58:30.467Z: JOB_MESSAGE_WARNING: Unable to delete temp files: "gs://temp-storage-for-perf-tests/loadtests/beamapp-jenkins-0425145734-004759-n1ymcj2q.1682434654.005116/dax-tmp-2023-04-25_07_57_34-9602294569630458528-S01-0-b6ff6cba5557e193/tmp-b6ff6cba5557e502@DAX.sdfmeta."
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:58:30.503Z: JOB_MESSAGE_WARNING: S01:ReadFromMongoDB/Read/Impulse+ReadFromMongoDB/Read/Map(<lambda at iobase.py:908>)+ref_AppliedPTransform_ReadFromMongoDB-Read-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_7/PairWithRestriction+ref_AppliedPTransform_ReadFromMongoDB-Read-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_7/SplitWithSizing failed.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:58:30.537Z: JOB_MESSAGE_BASIC: Finished operation ReadFromMongoDB/Read/Impulse+ReadFromMongoDB/Read/Map(<lambda at iobase.py:908>)+ref_AppliedPTransform_ReadFromMongoDB-Read-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_7/PairWithRestriction+ref_AppliedPTransform_ReadFromMongoDB-Read-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_7/SplitWithSizing
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:58:30.596Z: JOB_MESSAGE_DETAILED: Cleaning up.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:58:30.642Z: JOB_MESSAGE_DEBUG: Starting worker pool teardown.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:58:30.678Z: JOB_MESSAGE_BASIC: Stopping worker pool...
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:58:49.439Z: JOB_MESSAGE_BASIC: Worker pool stopped.
INFO:apache_beam.runners.dataflow.dataflow_runner:2023-04-25T14:58:49.469Z: JOB_MESSAGE_DEBUG: Tearing down pending resources...
INFO:apache_beam.runners.dataflow.dataflow_runner:Job 2023-04-25_07_57_34-9602294569630458528 is in state JOB_STATE_FAILED
ERROR:apache_beam.runners.dataflow.dataflow_runner:Console URL: https://console.cloud.google.com/dataflow/jobs/<RegionId>/2023-04-25_07_57_34-9602294569630458528?project=<ProjectId>
Traceback (most recent call last):
  File "/usr/lib/python3.7/runpy.py", line 193, in _run_module_as_main
    "__main__", mod_spec)
  File "/usr/lib/python3.7/runpy.py", line 85, in _run_code
    exec(code, run_globals)
  File "<https://ci-beam.apache.org/job/beam_python_mongoio_load_test/ws/src/build/gradleenv/-1734967052/lib/python3.7/site-packages/apache_beam/io/mongodbio_it_test.py",> line 170, in <module>
    run()
  File "<https://ci-beam.apache.org/job/beam_python_mongoio_load_test/ws/src/build/gradleenv/-1734967052/lib/python3.7/site-packages/apache_beam/io/mongodbio_it_test.py",> line 157, in run
    assert_that(r, equal_to([expected['number_sum'], expected['docs_count']]))
  File "<https://ci-beam.apache.org/job/beam_python_mongoio_load_test/ws/src/build/gradleenv/-1734967052/lib/python3.7/site-packages/apache_beam/pipeline.py",> line 600, in __exit__
    self.result = self.run()
  File "<https://ci-beam.apache.org/job/beam_python_mongoio_load_test/ws/src/build/gradleenv/-1734967052/lib/python3.7/site-packages/apache_beam/testing/test_pipeline.py",> line 116, in run
    state = result.wait_until_finish()
  File "<https://ci-beam.apache.org/job/beam_python_mongoio_load_test/ws/src/build/gradleenv/-1734967052/lib/python3.7/site-packages/apache_beam/runners/dataflow/dataflow_runner.py",> line 1558, in wait_until_finish
    self)
apache_beam.runners.dataflow.dataflow_runner.DataflowRuntimeException: Dataflow pipeline failed. State: FAILED, Error:
Workflow failed.

> Task :sdks:python:test-suites:dataflow:py37:mongodbioIT FAILED

FAILURE: Build failed with an exception.

* Where:
Script '<https://ci-beam.apache.org/job/beam_python_mongoio_load_test/ws/src/sdks/python/test-suites/dataflow/common.gradle'> line: 317

* What went wrong:
Execution failed for task ':sdks:python:test-suites:dataflow:py37:mongodbioIT'.
> Process 'command 'sh'' 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.5.1/userguide/command_line_interface.html#sec:command_line_warnings

BUILD FAILED in 15m 7s
15 actionable tasks: 9 executed, 4 from cache, 2 up-to-date
Build scan background action failed.
java.lang.IllegalArgumentException: com.gradle.enterprise.gradleplugin.internal.extension.a is not an interface
	at java.lang.reflect.Proxy$ProxyClassFactory.apply(Proxy.java:590)
	at java.lang.reflect.Proxy$ProxyClassFactory.apply(Proxy.java:557)
	at java.lang.reflect.WeakCache$Factory.get(WeakCache.java:230)
	at java.lang.reflect.WeakCache.get(WeakCache.java:127)
	at java.lang.reflect.Proxy.getProxyClass0(Proxy.java:419)
	at java.lang.reflect.Proxy.newProxyInstance(Proxy.java:719)
	at com.gradle.ProxyFactory$ProxyingInvocationHandler.createLocalProxy(ProxyFactory.java:64)
	at com.gradle.ProxyFactory$ProxyingInvocationHandler.lambda$adaptActionArg$1(ProxyFactory.java:59)
	at com.gradle.enterprise.gradleplugin.internal.extension.b$3.run(SourceFile:100)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:750)

Publishing build scan...
https://gradle.com/s/72722qzserdsq

Build step 'Invoke Gradle script' changed build result to FAILURE
Build step 'Invoke Gradle script' marked build as failure

---------------------------------------------------------------------
To unsubscribe, e-mail: builds-unsubscribe@beam.apache.org
For additional commands, e-mail: builds-help@beam.apache.org


Jenkins build is back to normal : beam_python_mongoio_load_test #4762

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://ci-beam.apache.org/job/beam_python_mongoio_load_test/4762/display/redirect>


---------------------------------------------------------------------
To unsubscribe, e-mail: builds-unsubscribe@beam.apache.org
For additional commands, e-mail: builds-help@beam.apache.org