See <https://ci-beam.apache.org/job/beam_PostCommit_Py_VR_Dataflow_V2/3547/display/redirect>
Changes: ------------------------------------------ [...truncated 101.10 KB...] [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.636Z: JOB_MESSAGE_BASIC: Starting 1 workers in us-central1-b... [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.691Z: JOB_MESSAGE_DEBUG: Starting worker pool setup. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:26:11.994Z: JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of workers to 1 so that the pipeline can catch up with its backlog and keep up with its input rate. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:26:16.664Z: 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 [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:26:45.524Z: JOB_MESSAGE_DETAILED: Workers have started successfully. [33mWARNING [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:253 Timing out on waiting for job 2022-06-12_09_25_40-2356741862892136032 after 61 seconds [31mFAILED[0m =================================== FAILURES =================================== [31m[1m_ ExerciseStreamingMetricsPipelineTest.test_streaming_pipeline_returns_expected_user_metrics_fnapi_it _[0m args = (subscription: "projects/apache-beam-testing/subscriptions/exercise_streaming_metrics_subscription_outputd2ca2ab2-486b-4c6f-a756-731a4b679b83" max_messages: 50 ,) kwargs = {'metadata': [('x-goog-request-params', 'subscription=projects/apache-beam-testing/subscriptions/exercise_streaming_me...6f-a756-731a4b679b83'), ('x-goog-api-client', 'gl-python/3.9.10 grpc/1.46.3 gax/1.31.6 gccl/2.13.0')], 'timeout': 30.0} [1m @six.wraps(callable_)[0m [1m def error_remapped_callable(*args, **kwargs):[0m [1m try:[0m [1m> return callable_(*args, **kwargs)[0m [1m[31m../../build/gradleenv/-1734967050/lib/python3.9/site-packages/google/api_core/grpc_helpers.py[0m:67: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ self = <grpc._channel._UnaryUnaryMultiCallable object at 0x7fef072a03a0> request = subscription: "projects/apache-beam-testing/subscriptions/exercise_streaming_metrics_subscription_outputd2ca2ab2-486b-4c6f-a756-731a4b679b83" max_messages: 50 timeout = 30.0 metadata = [('x-goog-request-params', 'subscription=projects/apache-beam-testing/subscriptions/exercise_streaming_metrics_subscri...utd2ca2ab2-486b-4c6f-a756-731a4b679b83'), ('x-goog-api-client', 'gl-python/3.9.10 grpc/1.46.3 gax/1.31.6 gccl/2.13.0')] credentials = None, wait_for_ready = None, compression = None [1m def __call__(self,[0m [1m request,[0m [1m timeout=None,[0m [1m metadata=None,[0m [1m credentials=None,[0m [1m wait_for_ready=None,[0m [1m compression=None):[0m [1m state, call, = self._blocking(request, timeout, metadata, credentials,[0m [1m wait_for_ready, compression)[0m [1m> return _end_unary_response_blocking(state, call, False, None)[0m [1m[31m../../build/gradleenv/-1734967050/lib/python3.9/site-packages/grpc/_channel.py[0m:946: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ state = <grpc._channel._RPCState object at 0x7fef0731c3a0> call = <grpc._cython.cygrpc.SegregatedCall object at 0x7fef07243580> with_call = False, deadline = None [1m def _end_unary_response_blocking(state, call, with_call, deadline):[0m [1m if state.code is grpc.StatusCode.OK:[0m [1m if with_call:[0m [1m rendezvous = _MultiThreadedRendezvous(state, call, None, deadline)[0m [1m return state.response, rendezvous[0m [1m else:[0m [1m return state.response[0m [1m else:[0m [1m> raise _InactiveRpcError(state)[0m [1m[31mE grpc._channel._InactiveRpcError: <_InactiveRpcError of RPC that terminated with:[0m [1m[31mE status = StatusCode.DEADLINE_EXCEEDED[0m [1m[31mE details = "Deadline Exceeded"[0m [1m[31mE debug_error_string = "{"created":"@1655051511.320223301","description":"Deadline Exceeded","file":"src/core/ext/filters/deadline/deadline_filter.cc","file_line":81,"grpc_status":4}"[0m [1m[31mE >[0m [1m[31m../../build/gradleenv/-1734967050/lib/python3.9/site-packages/grpc/_channel.py[0m:849: _InactiveRpcError [33mThe above exception was the direct cause of the following exception:[0m self = <apache_beam.runners.dataflow.dataflow_exercise_streaming_metrics_pipeline_test.ExerciseStreamingMetricsPipelineTest testMethod=test_streaming_pipeline_returns_expected_user_metrics_fnapi_it> [1m @pytest.mark.it_validatesrunner[0m [1m @pytest.mark.no_sickbay_batch[0m [1m @pytest.mark.no_xdist[0m [1m def test_streaming_pipeline_returns_expected_user_metrics_fnapi_it(self):[0m [1m """[0m [1m Runs streaming Dataflow job and verifies that user metrics are reported[0m [1m correctly.[0m [1m """[0m [1m self._inject_words(self.input_topic, MESSAGES_TO_PUBLISH)[0m [1m> result = self.run_pipeline()[0m [1m[31mapache_beam/runners/dataflow/dataflow_exercise_streaming_metrics_pipeline_test.py[0m:136: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [1m[31mapache_beam/runners/dataflow/dataflow_exercise_streaming_metrics_pipeline_test.py[0m:124: in run_pipeline [1m return dataflow_exercise_streaming_metrics_pipeline.run(argv)[0m [1m[31mapache_beam/runners/dataflow/dataflow_exercise_streaming_metrics_pipeline.py[0m:99: in run [1m result = pipeline.run()[0m [1m[31mapache_beam/pipeline.py[0m:573: in run [1m return self.runner.run_pipeline(self, self._options)[0m [1m[31mapache_beam/runners/dataflow/test_dataflow_runner.py[0m:70: in run_pipeline [1m hc_assert_that(self.result, pickler.loads(on_success_matcher))[0m [1m[31m../../build/gradleenv/-1734967050/lib/python3.9/site-packages/hamcrest/core/core/allof.py[0m:19: in matches [1m if not matcher.matches(item):[0m [1m[31m../../build/gradleenv/-1734967050/lib/python3.9/site-packages/hamcrest/core/base_matcher.py[0m:28: in matches [1m match_result = self._matches(item)[0m [1m[31mapache_beam/io/gcp/tests/pubsub_matcher.py[0m:116: in _matches [1m self.messages, self.messages_all_details = self._wait_for_messages([0m [1m[31mapache_beam/io/gcp/tests/pubsub_matcher.py[0m:131: in _wait_for_messages [1m response = sub_client.pull([0m [1m[31m../../build/gradleenv/-1734967050/lib/python3.9/site-packages/google/pubsub_v1/services/subscriber/client.py[0m:1402: in pull [1m response = rpc([0m [1m[31m../../build/gradleenv/-1734967050/lib/python3.9/site-packages/google/api_core/gapic_v1/method.py[0m:145: in __call__ [1m return wrapped_func(*args, **kwargs)[0m [1m[31m../../build/gradleenv/-1734967050/lib/python3.9/site-packages/google/api_core/retry.py[0m:286: in retry_wrapped_func [1m return retry_target([0m [1m[31m../../build/gradleenv/-1734967050/lib/python3.9/site-packages/google/api_core/retry.py[0m:189: in retry_target [1m return target()[0m [1m[31m../../build/gradleenv/-1734967050/lib/python3.9/site-packages/google/api_core/timeout.py[0m:102: in func_with_timeout [1m return func(*args, **kwargs)[0m [1m[31m../../build/gradleenv/-1734967050/lib/python3.9/site-packages/google/api_core/grpc_helpers.py[0m:69: in error_remapped_callable [1m six.raise_from(exceptions.from_grpc_error(exc), exc)[0m _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ value = None from_value = <_InactiveRpcError of RPC that terminated with: status = StatusCode.DEADLINE_EXCEEDED details = "Deadline Exceeded" ...ption":"Deadline Exceeded","file":"src/core/ext/filters/deadline/deadline_filter.cc","file_line":81,"grpc_status":4}" > [1m> ???[0m [1m[31mE google.api_core.exceptions.DeadlineExceeded: 504 Deadline Exceeded[0m [1m[31m<string>[0m:3: DeadlineExceeded ------------------------------ Captured log call ------------------------------- [32mINFO [0m apache_beam.runners.portability.stager:stager.py:754 Executing command: ['<https://ci-beam.apache.org/job/beam_PostCommit_Py_VR_Dataflow_V2/ws/src/build/gradleenv/-1734967050/bin/python3.9',> '-m', 'pip', 'download', '--dest', '/tmp/dataflow-requirements-cache', '-r', '/tmp/tmpuw9ng4_7/tmp_requirements.txt', '--exists-action', 'i', '--no-deps', '--implementation', 'cp', '--abi', 'cp39', '--platform', 'manylinux2014_x86_64'] [32mINFO [0m apache_beam.runners.portability.stager:stager.py:325 Copying Beam SDK "<https://ci-beam.apache.org/job/beam_PostCommit_Py_VR_Dataflow_V2/ws/src/sdks/python/build/apache-beam.tar.gz";> to staging location. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:476 Pipeline has additional dependencies to be installed in SDK worker container, consider using the SDK container image pre-building workflow to avoid repetitive installations. Learn more on https://cloud.google.com/dataflow/docs/guides/using-custom-containers#prebuild [32mINFO [0m root:environments.py:376 Default Python SDK image for environment is apache/beam_python3.9_sdk:2.40.0.dev [32mINFO [0m root:environments.py:295 Using provided Python SDK container image: gcr.io/cloud-dataflow/v1beta3/python39-fnapi:beam-master-20220512 [32mINFO [0m root:environments.py:302 Python SDK container image set to "gcr.io/cloud-dataflow/v1beta3/python39-fnapi:beam-master-20220512" for Docker environment [32mINFO [0m apache_beam.internal.gcp.auth:auth.py:136 Setting socket default timeout to 60 seconds. [32mINFO [0m apache_beam.internal.gcp.auth:auth.py:138 socket default timeout is 60.0 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/requirements.txt... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/requirements.txt in 0 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/pickled_main_session... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/pickled_main_session in 0 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/mock-2.0.0-py2.py3-none-any.whl... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/mock-2.0.0-py2.py3-none-any.whl in 0 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/seaborn-0.11.2-py3-none-any.whl... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/seaborn-0.11.2-py3-none-any.whl in 0 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/PyHamcrest-1.10.1-py3-none-any.whl... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/PyHamcrest-1.10.1-py3-none-any.whl in 0 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/beautifulsoup4-4.11.1-py3-none-any.whl... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/beautifulsoup4-4.11.1-py3-none-any.whl in 0 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/parameterized-0.7.5-py2.py3-none-any.whl... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/parameterized-0.7.5-py2.py3-none-any.whl in 0 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/matplotlib-3.5.2-cp39-cp39-manylinux_2_5_x86_64.manylinux1_x86_64.whl... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/matplotlib-3.5.2-cp39-cp39-manylinux_2_5_x86_64.manylinux1_x86_64.whl in 0 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/matplotlib-3.5.1-cp37-cp37m-manylinux_2_5_x86_64.manylinux1_x86_64.whl... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/matplotlib-3.5.1-cp37-cp37m-manylinux_2_5_x86_64.manylinux1_x86_64.whl in 0 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/matplotlib-3.5.2-cp37-cp37m-manylinux_2_5_x86_64.manylinux1_x86_64.whl... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/matplotlib-3.5.2-cp37-cp37m-manylinux_2_5_x86_64.manylinux1_x86_64.whl in 0 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/dataflow_python_sdk.tar... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/dataflow_python_sdk.tar in 0 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/dataflow-worker.jar... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/dataflow-worker.jar in 5 seconds. [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:718 Starting GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/pipeline.pb... [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:734 Completed GCS upload to gs://temp-storage-for-end-to-end-tests/staging-it/beamapp-jenkins-0612162531-995974-nthvafm2.1655051131.996148/pipeline.pb in 0 seconds. [33mWARNING [0m apache_beam.options.pipeline_options:pipeline_options.py:335 Discarding unparseable args: ['--sleep_secs=20'] [33mWARNING [0m apache_beam.options.pipeline_options:pipeline_options.py:335 Discarding unparseable args: ['--sleep_secs=20'] [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:897 Create job: <Job clientRequestId: '20220612162531997453-2535' createTime: '2022-06-12T16:25:41.442461Z' currentStateTime: '1970-01-01T00:00:00Z' id: '2022-06-12_09_25_40-2356741862892136032' location: 'us-central1' name: 'beamapp-jenkins-0612162531-995974-nthvafm2' projectId: 'apache-beam-testing' stageStates: [] startTime: '2022-06-12T16:25:41.442461Z' steps: [] tempFiles: [] type: TypeValueValuesEnum(JOB_TYPE_STREAMING, 2)> [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:899 Created job with id: [2022-06-12_09_25_40-2356741862892136032] [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:900 Submitted job: 2022-06-12_09_25_40-2356741862892136032 [32mINFO [0m apache_beam.runners.dataflow.internal.apiclient:apiclient.py:901 To access the Dataflow monitoring console, please navigate to https://console.cloud.google.com/dataflow/jobs/us-central1/2022-06-12_09_25_40-2356741862892136032?project=apache-beam-testing [32mINFO [0m apache_beam.runners.dataflow.test_dataflow_runner:test_dataflow_runner.py:58 Console log: [32mINFO [0m apache_beam.runners.dataflow.test_dataflow_runner:test_dataflow_runner.py:59 https://console.cloud.google.com/dataflow/jobs/us-central1/2022-06-12_09_25_40-2356741862892136032?project=apache-beam-testing [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:197 Job 2022-06-12_09_25_40-2356741862892136032 is in state JOB_STATE_RUNNING [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:42.228Z: JOB_MESSAGE_WARNING: Autoscaling is enabled for Dataflow Streaming Engine. Workers will scale between 1 and 100 unless maxNumWorkers is specified. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:43.592Z: JOB_MESSAGE_DETAILED: Autoscaling is enabled for job 2022-06-12_09_25_40-2356741862892136032. The number of workers will be between 1 and 100. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:43.629Z: JOB_MESSAGE_DETAILED: Autoscaling was automatically enabled for job 2022-06-12_09_25_40-2356741862892136032. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:47.864Z: JOB_MESSAGE_BASIC: Worker configuration: e2-standard-2 in us-central1-b. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:48.956Z: JOB_MESSAGE_DETAILED: Expanding SplittableParDo operations into optimizable parts. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:48.988Z: JOB_MESSAGE_DETAILED: Expanding CollectionToSingleton operations into optimizable parts. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.053Z: JOB_MESSAGE_DETAILED: Expanding CoGroupByKey operations into optimizable parts. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.098Z: JOB_MESSAGE_DETAILED: Expanding SplittableProcessKeyed operations into optimizable parts. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.126Z: JOB_MESSAGE_DETAILED: Expanding GroupByKey operations into streaming Read/Write steps [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.152Z: JOB_MESSAGE_DETAILED: Lifting ValueCombiningMappingFns into MergeBucketsMappingFns [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.211Z: JOB_MESSAGE_DEBUG: Annotating graph with Autotuner information. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.267Z: JOB_MESSAGE_DETAILED: Fusing adjacent ParDo, Read, Write, and Flatten operations [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.298Z: JOB_MESSAGE_DETAILED: Fusing consumer generate_metrics into ReadFromPubSub/Read [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.333Z: JOB_MESSAGE_DETAILED: Fusing consumer dump_to_pub/ToProtobuf into generate_metrics [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.368Z: JOB_MESSAGE_DETAILED: Fusing consumer dump_to_pub/Write into dump_to_pub/ToProtobuf [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.410Z: JOB_MESSAGE_BASIC: Running job using Streaming Engine [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.439Z: JOB_MESSAGE_DEBUG: Workflow config is missing a default resource spec. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.470Z: JOB_MESSAGE_DEBUG: Adding StepResource setup and teardown to workflow graph. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.507Z: JOB_MESSAGE_DEBUG: Adding workflow start and stop steps. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.544Z: JOB_MESSAGE_DEBUG: Assigning stage ids. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.604Z: JOB_MESSAGE_DEBUG: Starting worker pool setup. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.636Z: JOB_MESSAGE_BASIC: Starting 1 workers in us-central1-b... [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:25:49.691Z: JOB_MESSAGE_DEBUG: Starting worker pool setup. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:26:11.994Z: JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of workers to 1 so that the pipeline can catch up with its backlog and keep up with its input rate. [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:26:16.664Z: 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 [32mINFO [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:242 2022-06-12T16:26:45.524Z: JOB_MESSAGE_DETAILED: Workers have started successfully. [33mWARNING [0m apache_beam.runners.dataflow.dataflow_runner:dataflow_runner.py:253 Timing out on waiting for job 2022-06-12_09_25_40-2356741862892136032 after 61 seconds [33m=============================== warnings summary ===============================[0m <https://ci-beam.apache.org/job/beam_PostCommit_Py_VR_Dataflow_V2/ws/src/build/gradleenv/-1734967050/lib/python3.9/site-packages/tenacity/_asyncio.py>:42 <https://ci-beam.apache.org/job/beam_PostCommit_Py_VR_Dataflow_V2/ws/src/build/gradleenv/-1734967050/lib/python3.9/site-packages/tenacity/_asyncio.py>:42: DeprecationWarning: "@coroutine" decorator is deprecated since Python 3.8, use "async def" instead def call(self, fn, *args, **kwargs): -- Docs: https://docs.pytest.org/en/latest/warnings.html - generated xml file: <https://ci-beam.apache.org/job/beam_PostCommit_Py_VR_Dataflow_V2/ws/src/sdks/python/pytest_validatesRunnerStreamingTests-df-py39-noxdist.xml> - [31m[1m====== 1 failed, 1 skipped, 5719 deselected, 1 warnings in 459.07 seconds ======[0m > Task :sdks:python:test-suites:dataflow:py39:validatesRunnerStreamingTests > FAILED FAILURE: Build failed with an exception. * Where: Script '<https://ci-beam.apache.org/job/beam_PostCommit_Py_VR_Dataflow_V2/ws/src/sdks/python/test-suites/dataflow/common.gradle'> line: 287 * What went wrong: Execution failed for task ':sdks:python:test-suites:dataflow:py39:validatesRunnerStreamingTests'. > 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. > 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.4/userguide/command_line_interface.html#sec:command_line_warnings BUILD FAILED in 1h 24m 28s 93 actionable tasks: 58 executed, 33 from cache, 2 up-to-date Publishing build scan... https://gradle.com/s/eu37didah3sgo 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]
