See 
<https://builds.apache.org/job/beam_PerformanceTests_WordCountIT_Py27/1527/display/redirect>

Changes:


------------------------------------------
[...truncated 234.48 KB...]
            "step_name": "SideInput-s18"
          }, 
          "python_side_input1-write/Write/WriteImpl/FinalizeWrite": {
            "@type": "OutputReference", 
            "output_name": "out", 
            "step_name": "SideInput-s19"
          }, 
          "python_side_input2-write/Write/WriteImpl/FinalizeWrite": {
            "@type": "OutputReference", 
            "output_name": "out", 
            "step_name": "SideInput-s20"
          }
        }, 
        "output_info": [
          {
            "encoding": {
              "@type": "kind:windowed_value", 
              "component_encodings": [
                {
                  "@type": 
"FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/",
 
                  "component_encodings": [
                    {
                      "@type": 
"FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/",
 
                      "component_encodings": [], 
                      "pipeline_proto_coder_id": 
"ref_Coder_FastPrimitivesCoder_5"
                    }, 
                    {
                      "@type": 
"FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/",
 
                      "component_encodings": [], 
                      "pipeline_proto_coder_id": 
"ref_Coder_FastPrimitivesCoder_5"
                    }
                  ], 
                  "is_pair_like": true, 
                  "pipeline_proto_coder_id": "ref_Coder_FastPrimitivesCoder_5"
                }, 
                {
                  "@type": "kind:global_window"
                }
              ], 
              "is_wrapper": true
            }, 
            "output_name": "None", 
            "user_name": "write/Write/WriteImpl/FinalizeWrite.out"
          }
        ], 
        "parallel_input": {
          "@type": "OutputReference", 
          "output_name": "out", 
          "step_name": "s7"
        }, 
        "serialized_fn": "<string of 3004 bytes>", 
        "user_name": "write/Write/WriteImpl/FinalizeWrite/FinalizeWrite"
      }
    }
  ], 
  "type": "JOB_TYPE_BATCH"
}
apache_beam.runners.dataflow.internal.apiclient: INFO: Create job: <Job
 createTime: u'2020-06-02T09:14:03.109604Z'
 currentStateTime: u'1970-01-01T00:00:00Z'
 id: u'2020-06-02_02_14_01-4242998202347119265'
 location: u'us-central1'
 name: u'performance-tests-wordcount-python27-batch-1gb0602091047'
 projectId: u'apache-beam-testing'
 stageStates: []
 startTime: u'2020-06-02T09:14:03.109604Z'
 steps: []
 tempFiles: []
 type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)>
apache_beam.runners.dataflow.internal.apiclient: INFO: Created job with id: 
[2020-06-02_02_14_01-4242998202347119265]
apache_beam.runners.dataflow.internal.apiclient: INFO: Submitted job: 
2020-06-02_02_14_01-4242998202347119265
apache_beam.runners.dataflow.internal.apiclient: INFO: To access the Dataflow 
monitoring console, please navigate to 
https://console.cloud.google.com/dataflow/jobs/us-central1/2020-06-02_02_14_01-4242998202347119265?project=apache-beam-testing
apache_beam.runners.dataflow.dataflow_runner: INFO: Job 
2020-06-02_02_14_01-4242998202347119265 is in state JOB_STATE_RUNNING
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:05.766Z: 
JOB_MESSAGE_BASIC: Worker configuration: n1-standard-1 in us-central1-a.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:06.639Z: 
JOB_MESSAGE_DETAILED: Expanding CoGroupByKey operations into optimizable parts.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:06.681Z: 
JOB_MESSAGE_DEBUG: Combiner lifting skipped for step 
write/Write/WriteImpl/GroupByKey: GroupByKey not followed by a combiner.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:06.721Z: 
JOB_MESSAGE_DEBUG: Combiner lifting skipped for step group: GroupByKey not 
followed by a combiner.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:06.773Z: 
JOB_MESSAGE_DETAILED: Expanding GroupByKey operations into optimizable parts.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:06.809Z: 
JOB_MESSAGE_DETAILED: Lifting ValueCombiningMappingFns into 
MergeBucketsMappingFns
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:06.899Z: 
JOB_MESSAGE_DEBUG: Annotating graph with Autotuner information.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:06.956Z: 
JOB_MESSAGE_DETAILED: Fusing adjacent ParDo, Read, Write, and Flatten operations
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:06.993Z: 
JOB_MESSAGE_DETAILED: Fusing consumer split into read/Read
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.021Z: 
JOB_MESSAGE_DETAILED: Fusing consumer pair_with_one into split
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.060Z: 
JOB_MESSAGE_DETAILED: Fusing consumer group/Reify into pair_with_one
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.087Z: 
JOB_MESSAGE_DETAILED: Fusing consumer group/Write into group/Reify
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.127Z: 
JOB_MESSAGE_DETAILED: Fusing consumer group/GroupByWindow into group/Read
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.158Z: 
JOB_MESSAGE_DETAILED: Fusing consumer count into group/GroupByWindow
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.194Z: 
JOB_MESSAGE_DETAILED: Fusing consumer format into count
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.235Z: 
JOB_MESSAGE_DETAILED: Fusing consumer 
write/Write/WriteImpl/WindowInto(WindowIntoFn) into format
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.269Z: 
JOB_MESSAGE_DETAILED: Fusing consumer 
write/Write/WriteImpl/WriteBundles/WriteBundles into 
write/Write/WriteImpl/WindowInto(WindowIntoFn)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.301Z: 
JOB_MESSAGE_DETAILED: Fusing consumer write/Write/WriteImpl/Pair into 
write/Write/WriteImpl/WriteBundles/WriteBundles
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.366Z: 
JOB_MESSAGE_DETAILED: Fusing consumer write/Write/WriteImpl/GroupByKey/Reify 
into write/Write/WriteImpl/Pair
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.404Z: 
JOB_MESSAGE_DETAILED: Fusing consumer write/Write/WriteImpl/GroupByKey/Write 
into write/Write/WriteImpl/GroupByKey/Reify
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.441Z: 
JOB_MESSAGE_DETAILED: Fusing consumer 
write/Write/WriteImpl/GroupByKey/GroupByWindow into 
write/Write/WriteImpl/GroupByKey/Read
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.479Z: 
JOB_MESSAGE_DETAILED: Fusing consumer write/Write/WriteImpl/Extract into 
write/Write/WriteImpl/GroupByKey/GroupByWindow
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.513Z: 
JOB_MESSAGE_DETAILED: Fusing consumer write/Write/WriteImpl/InitializeWrite 
into write/Write/WriteImpl/DoOnce/Read
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.557Z: 
JOB_MESSAGE_DEBUG: Workflow config is missing a default resource spec.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.606Z: 
JOB_MESSAGE_DEBUG: Adding StepResource setup and teardown to workflow graph.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.648Z: 
JOB_MESSAGE_DEBUG: Adding workflow start and stop steps.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.689Z: 
JOB_MESSAGE_DEBUG: Assigning stage ids.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:07.937Z: 
JOB_MESSAGE_DEBUG: Executing wait step start26
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:08.017Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/DoOnce/Read+write/Write/WriteImpl/InitializeWrite
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:08.058Z: 
JOB_MESSAGE_BASIC: Executing operation write/Write/WriteImpl/GroupByKey/Create
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:08.070Z: 
JOB_MESSAGE_DEBUG: Starting **** pool setup.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:08.096Z: 
JOB_MESSAGE_BASIC: Executing operation group/Create
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:08.112Z: 
JOB_MESSAGE_BASIC: Starting 10 ****s in us-central1-a...
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:08.183Z: 
JOB_MESSAGE_BASIC: Finished operation write/Write/WriteImpl/GroupByKey/Create
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:08.183Z: 
JOB_MESSAGE_BASIC: Finished operation group/Create
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:08.271Z: 
JOB_MESSAGE_DEBUG: Value "group/Session" materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:08.307Z: 
JOB_MESSAGE_DEBUG: Value "write/Write/WriteImpl/GroupByKey/Session" 
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:08.363Z: 
JOB_MESSAGE_BASIC: Executing operation 
read/Read+split+pair_with_one+group/Reify+group/Write
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:15.787Z: 
JOB_MESSAGE_WARNING: Your project already contains 100 Dataflow-created metric 
descriptors and Stackdriver will not create new Dataflow custom metrics for 
this job. Each unique user-defined metric name (independent of the DoFn in 
which it is defined) produces a new metric descriptor. To 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
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:40.202Z: 
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of ****s to 3 based on the 
rate of progress in the currently running stage(s).
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:40.244Z: 
JOB_MESSAGE_DETAILED: Resized **** pool to 3, though goal was 10.  This could 
be a quota issue.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:14:45.636Z: 
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of ****s to 10 based on 
the rate of progress in the currently running stage(s).
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:16:07.970Z: 
JOB_MESSAGE_DETAILED: Workers have started successfully.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:16:08.014Z: 
JOB_MESSAGE_DETAILED: Workers have started successfully.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:18:59.948Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/DoOnce/Read+write/Write/WriteImpl/InitializeWrite
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:19:00.031Z: 
JOB_MESSAGE_DEBUG: Value "write/Write/WriteImpl/DoOnce/Read.out" materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:19:00.083Z: 
JOB_MESSAGE_DEBUG: Value "write/Write/WriteImpl/InitializeWrite.out" 
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:19:00.162Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/WriteBundles/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:19:00.198Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:19:00.216Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/WriteBundles/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:19:00.245Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:19:00.250Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:19:00.280Z: 
JOB_MESSAGE_DEBUG: Value 
"write/Write/WriteImpl/WriteBundles/_UnpickledSideInput(InitializeWrite.out.0).output"
 materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:19:00.297Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:19:00.311Z: 
JOB_MESSAGE_DEBUG: Value 
"write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(InitializeWrite.out.0).output"
 materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:19:00.373Z: 
JOB_MESSAGE_DEBUG: Value 
"write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(InitializeWrite.out.0).output"
 materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:21:12.518Z: 
JOB_MESSAGE_BASIC: Finished operation 
read/Read+split+pair_with_one+group/Reify+group/Write
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:21:12.604Z: 
JOB_MESSAGE_BASIC: Executing operation group/Close
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:21:12.666Z: 
JOB_MESSAGE_BASIC: Finished operation group/Close
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:21:12.754Z: 
JOB_MESSAGE_BASIC: Executing operation 
group/Read+group/GroupByWindow+count+format+write/Write/WriteImpl/WindowInto(WindowIntoFn)+write/Write/WriteImpl/WriteBundles/WriteBundles+write/Write/WriteImpl/Pair+write/Write/WriteImpl/GroupByKey/Reify+write/Write/WriteImpl/GroupByKey/Write
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:34.950Z: 
JOB_MESSAGE_BASIC: Finished operation 
group/Read+group/GroupByWindow+count+format+write/Write/WriteImpl/WindowInto(WindowIntoFn)+write/Write/WriteImpl/WriteBundles/WriteBundles+write/Write/WriteImpl/Pair+write/Write/WriteImpl/GroupByKey/Reify+write/Write/WriteImpl/GroupByKey/Write
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:35.037Z: 
JOB_MESSAGE_BASIC: Executing operation write/Write/WriteImpl/GroupByKey/Close
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:35.092Z: 
JOB_MESSAGE_BASIC: Finished operation write/Write/WriteImpl/GroupByKey/Close
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:35.164Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/GroupByKey/Read+write/Write/WriteImpl/GroupByKey/GroupByWindow+write/Write/WriteImpl/Extract
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:37.846Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/GroupByKey/Read+write/Write/WriteImpl/GroupByKey/GroupByWindow+write/Write/WriteImpl/Extract
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:37.925Z: 
JOB_MESSAGE_DEBUG: Value "write/Write/WriteImpl/Extract.out" materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:37.992Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(Extract.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:38.032Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(Extract.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:38.048Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(Extract.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:38.094Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(Extract.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:38.111Z: 
JOB_MESSAGE_DEBUG: Value 
"write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(Extract.out.0).output" 
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:38.166Z: 
JOB_MESSAGE_DEBUG: Value 
"write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(Extract.out.0).output" 
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:38.241Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/PreFinalize/PreFinalize
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:41.066Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/PreFinalize/PreFinalize
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:41.138Z: 
JOB_MESSAGE_DEBUG: Value "write/Write/WriteImpl/PreFinalize.out" materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:41.211Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:41.265Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:41.329Z: 
JOB_MESSAGE_DEBUG: Value 
"write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0).output"
 materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:41.392Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/FinalizeWrite/FinalizeWrite
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:43.930Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/FinalizeWrite/FinalizeWrite
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:44Z: 
JOB_MESSAGE_DEBUG: Executing success step success24
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:44.130Z: 
JOB_MESSAGE_DETAILED: Cleaning up.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:44.194Z: 
JOB_MESSAGE_DEBUG: Starting **** pool teardown.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:24:44.222Z: 
JOB_MESSAGE_BASIC: Stopping **** pool...
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:26:39.532Z: 
JOB_MESSAGE_DETAILED: Autoscaling: Resized **** pool from 10 to 0.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:26:39.580Z: 
JOB_MESSAGE_BASIC: Worker pool stopped.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-06-02T09:26:39.622Z: 
JOB_MESSAGE_DEBUG: Tearing down pending resources...
apache_beam.runners.dataflow.dataflow_runner: INFO: Job 
2020-06-02_02_14_01-4242998202347119265 is in state JOB_STATE_DONE
apache_beam.io.filesystem: DEBUG: Listing files in 
'gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results'
apache_beam.io.filesystem: DEBUG: translate_pattern: 
'gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results*-of-*'
 -> 
'gs\\:\\/\\/temp\\-storage\\-for\\-end\\-to\\-end\\-tests\\/py\\-it\\-cloud\\/output\\/1591089236932\\/results[^/\\\\]*\\-of\\-[^/\\\\]*'
apache_beam.io.gcp.gcsio: INFO: Starting the size estimation of the input
apache_beam.io.gcp.gcsio: INFO: Finished listing 30 files in 0.0821170806885 
seconds.
apache_beam.testing.pipeline_verifiers: INFO: Find 30 files in 
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results*-of-*:
 
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00007-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00012-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00014-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00000-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00001-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00011-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00002-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00023-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00024-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00004-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00020-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00029-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00026-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00028-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00010-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00005-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00003-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00017-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00016-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00006-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00027-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00015-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00021-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00022-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00013-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00008-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00009-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00019-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00025-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results-00018-of-00030
apache_beam.testing.pipeline_verifiers: INFO: Read from given path 
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1591089236932/results*-of-*,
 26186927 lines, checksum: ea0ca2e5ee4ea5f218790f28d0b9fe7d09d8d710.
root: INFO: average word length: 19
google.auth.transport._http_client: DEBUG: Making request: GET 
http://169.254.169.254
google.auth.transport._http_client: DEBUG: Making request: GET 
http://metadata.google.internal/computeMetadata/v1/project/project-id
urllib3.util.retry: DEBUG: Converted retries value: 3 -> Retry(total=3, 
connect=None, read=None, redirect=None, status=None)
google.auth.transport.requests: DEBUG: Making request: GET 
http://metadata.google.internal/computeMetadata/v1/instance/service-accounts/default/?recursive=true
urllib3.connectionpool: DEBUG: Starting new HTTP connection (1): 
metadata.google.internal:80
urllib3.connectionpool: DEBUG: http://metadata.google.internal:80 "GET 
/computeMetadata/v1/instance/service-accounts/default/?recursive=true HTTP/1.1" 
200 144
google.auth.transport.requests: DEBUG: Making request: GET 
http://metadata.google.internal/computeMetadata/v1/instance/service-accounts/[email protected]/token
urllib3.connectionpool: DEBUG: http://metadata.google.internal:80 "GET 
/computeMetadata/v1/instance/service-accounts/[email protected]/token
 HTTP/1.1" 200 192
urllib3.connectionpool: DEBUG: Starting new HTTPS connection (1): 
bigquery.googleapis.com:443
urllib3.connectionpool: DEBUG: https://bigquery.googleapis.com:443 "GET 
/bigquery/v2/projects/apache-beam-testing/datasets/beam_performance HTTP/1.1" 
200 None
urllib3.connectionpool: DEBUG: https://bigquery.googleapis.com:443 "GET 
/bigquery/v2/projects/apache-beam-testing/datasets/beam_performance/tables/wordcount_py27_pkb_results
 HTTP/1.1" 200 None
--------------------- >> end captured logging << ---------------------

----------------------------------------------------------------------
XML: nosetests-runPerformanceTest-df-py27.xml
----------------------------------------------------------------------
XML: 
<https://builds.apache.org/job/beam_PerformanceTests_WordCountIT_Py27/ws/src/sdks/python/nosetests.xml>
----------------------------------------------------------------------
Ran 1 test in 887.992s

FAILED (errors=1)

> Task :sdks:python:test-suites:dataflow:py2:runPerformanceTest FAILED
:sdks:python:test-suites:dataflow:py2:runPerformanceTest (Thread[Execution **** 
for ':',5,main]) completed. Took 14 mins 51.04 secs.

FAILURE: Build failed with an exception.

* Where:
Script 
'<https://builds.apache.org/job/beam_PerformanceTests_WordCountIT_Py27/ws/src/sdks/python/test-suites/dataflow/common.gradle'>
 line: 192

* What went wrong:
Execution failed for task 
':sdks:python:test-suites:dataflow:py2:runPerformanceTest'.
> Process 'command 'sh'' finished with non-zero exit value 1

* Try:
Run with --stacktrace option to get the stack trace. Run with --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 6.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See 
https://docs.gradle.org/5.2.1/userguide/command_line_interface.html#sec:command_line_warnings

BUILD FAILED in 16m 19s
5 actionable tasks: 5 executed

Publishing build scan...
https://gradle.com/s/z2abnlacmtpyc

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]

Reply via email to