See 
<https://builds.apache.org/job/beam_PerformanceTests_WordCountIT_Py27/1210/display/redirect?page=changes>

Changes:

[mxm] [BEAM-9490] Guard referencing for environment expiration via a lock

[thw] [BEAM-9490] Use the lock that belongs to the cache when bundle load


------------------------------------------
[...truncated 451.89 KB...]
      "name": "s21", 
      "properties": {
        "display_data": [
          {
            "key": "fn", 
            "label": "Transform Function", 
            "namespace": "apache_beam.transforms.core.CallableWrapperDoFn", 
            "type": "STRING", 
            "value": "_finalize_write"
          }, 
          {
            "key": "fn", 
            "label": "Transform Function", 
            "namespace": "apache_beam.transforms.core.ParDo", 
            "shortValue": "CallableWrapperDoFn", 
            "type": "STRING", 
            "value": "apache_beam.transforms.core.CallableWrapperDoFn"
          }
        ], 
        "non_parallel_inputs": {
          "side0-write/Write/WriteImpl/FinalizeWrite": {
            "@type": "OutputReference", 
            "output_name": "out", 
            "step_name": "SideInput-s18"
          }, 
          "side1-write/Write/WriteImpl/FinalizeWrite": {
            "@type": "OutputReference", 
            "output_name": "out", 
            "step_name": "SideInput-s19"
          }, 
          "side2-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 2500 bytes>", 
        "user_name": "write/Write/WriteImpl/FinalizeWrite/FinalizeWrite"
      }
    }
  ], 
  "type": "JOB_TYPE_BATCH"
}
INFO:apache_beam.runners.dataflow.internal.apiclient:Create job: <Job
 createTime: u'2020-03-14T18:35:26.397956Z'
 currentStateTime: u'1970-01-01T00:00:00Z'
 id: u'2020-03-14_11_35_25-12215412939888866684'
 location: u'us-central1'
 name: u'beamapp-jenkins-0314183523-080390'
 projectId: u'apache-beam-testing'
 stageStates: []
 startTime: u'2020-03-14T18:35:26.397956Z'
 steps: []
 tempFiles: []
 type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)>
INFO:apache_beam.runners.dataflow.internal.apiclient:Created job with id: 
[2020-03-14_11_35_25-12215412939888866684]
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/2020-03-14_11_35_25-12215412939888866684?project=apache-beam-testing
INFO:apache_beam.runners.dataflow.dataflow_runner:Job 
2020-03-14_11_35_25-12215412939888866684 is in state JOB_STATE_RUNNING
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:26.685Z: 
JOB_MESSAGE_DETAILED: Checking permissions granted to controller Service 
Account.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:27.137Z: 
JOB_MESSAGE_BASIC: Worker configuration: n1-standard-1 in us-central1-c.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:27.770Z: 
JOB_MESSAGE_DETAILED: Expanding CoGroupByKey operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:27.809Z: 
JOB_MESSAGE_DEBUG: Combiner lifting skipped for step 
write/Write/WriteImpl/GroupByKey: GroupByKey not followed by a combiner.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:27.845Z: 
JOB_MESSAGE_DEBUG: Combiner lifting skipped for step group: GroupByKey not 
followed by a combiner.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:27.875Z: 
JOB_MESSAGE_DETAILED: Expanding GroupByKey operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:27.907Z: 
JOB_MESSAGE_DETAILED: Lifting ValueCombiningMappingFns into 
MergeBucketsMappingFns
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.009Z: 
JOB_MESSAGE_DEBUG: Annotating graph with Autotuner information.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.058Z: 
JOB_MESSAGE_DETAILED: Fusing adjacent ParDo, Read, Write, and Flatten operations
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.093Z: 
JOB_MESSAGE_DETAILED: Fusing consumer split into read/Read
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.124Z: 
JOB_MESSAGE_DETAILED: Fusing consumer pair_with_one into split
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.159Z: 
JOB_MESSAGE_DETAILED: Fusing consumer group/Reify into pair_with_one
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.197Z: 
JOB_MESSAGE_DETAILED: Fusing consumer group/Write into group/Reify
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.230Z: 
JOB_MESSAGE_DETAILED: Fusing consumer group/GroupByWindow into group/Read
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.266Z: 
JOB_MESSAGE_DETAILED: Fusing consumer count into group/GroupByWindow
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.302Z: 
JOB_MESSAGE_DETAILED: Fusing consumer format into count
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.329Z: 
JOB_MESSAGE_DETAILED: Fusing consumer 
write/Write/WriteImpl/WriteBundles/WriteBundles into format
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.361Z: 
JOB_MESSAGE_DETAILED: Fusing consumer write/Write/WriteImpl/Pair into 
write/Write/WriteImpl/WriteBundles/WriteBundles
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.396Z: 
JOB_MESSAGE_DETAILED: Fusing consumer 
write/Write/WriteImpl/WindowInto(WindowIntoFn) into write/Write/WriteImpl/Pair
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.476Z: 
JOB_MESSAGE_DETAILED: Fusing consumer write/Write/WriteImpl/GroupByKey/Reify 
into write/Write/WriteImpl/WindowInto(WindowIntoFn)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.534Z: 
JOB_MESSAGE_DETAILED: Fusing consumer write/Write/WriteImpl/GroupByKey/Write 
into write/Write/WriteImpl/GroupByKey/Reify
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.574Z: 
JOB_MESSAGE_DETAILED: Fusing consumer 
write/Write/WriteImpl/GroupByKey/GroupByWindow into 
write/Write/WriteImpl/GroupByKey/Read
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.623Z: 
JOB_MESSAGE_DETAILED: Fusing consumer write/Write/WriteImpl/Extract into 
write/Write/WriteImpl/GroupByKey/GroupByWindow
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.734Z: 
JOB_MESSAGE_DETAILED: Fusing consumer write/Write/WriteImpl/InitializeWrite 
into write/Write/WriteImpl/DoOnce/Read
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.763Z: 
JOB_MESSAGE_DEBUG: Workflow config is missing a default resource spec.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.800Z: 
JOB_MESSAGE_DEBUG: Adding StepResource setup and teardown to workflow graph.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.840Z: 
JOB_MESSAGE_DEBUG: Adding workflow start and stop steps.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:28.880Z: 
JOB_MESSAGE_DEBUG: Assigning stage ids.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:29.085Z: 
JOB_MESSAGE_DEBUG: Executing wait step start26
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:29.156Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/DoOnce/Read+write/Write/WriteImpl/InitializeWrite
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:29.180Z: 
JOB_MESSAGE_BASIC: Executing operation write/Write/WriteImpl/GroupByKey/Create
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:29.202Z: 
JOB_MESSAGE_DEBUG: Starting worker pool setup.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:29.218Z: 
JOB_MESSAGE_BASIC: Executing operation group/Create
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:29.236Z: 
JOB_MESSAGE_BASIC: Starting 10 workers in us-central1-c...
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:29.295Z: 
JOB_MESSAGE_BASIC: Finished operation group/Create
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:29.295Z: 
JOB_MESSAGE_BASIC: Finished operation write/Write/WriteImpl/GroupByKey/Create
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:29.357Z: 
JOB_MESSAGE_DEBUG: Value "group/Session" materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:29.393Z: 
JOB_MESSAGE_DEBUG: Value "write/Write/WriteImpl/GroupByKey/Session" 
materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:29.427Z: 
JOB_MESSAGE_BASIC: Executing operation 
read/Read+split+pair_with_one+group/Reify+group/Write
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:37.748Z: 
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
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:53.580Z: 
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of workers to 2 based on 
the rate of progress in the currently running step(s).
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:53.614Z: 
JOB_MESSAGE_DETAILED: Resized worker pool to 2, though goal was 10.  This could 
be a quota issue.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:58.981Z: 
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of workers to 3 based on 
the rate of progress in the currently running step(s).
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:36:59.014Z: 
JOB_MESSAGE_DETAILED: Resized worker pool to 3, though goal was 10.  This could 
be a quota issue.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:37:04.395Z: 
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of workers to 5 based on 
the rate of progress in the currently running step(s).
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:37:04.434Z: 
JOB_MESSAGE_DETAILED: Resized worker pool to 5, though goal was 10.  This could 
be a quota issue.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:37:09.941Z: 
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of workers to 8 based on 
the rate of progress in the currently running step(s).
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:37:09.973Z: 
JOB_MESSAGE_DETAILED: Resized worker pool to 8, though goal was 10.  This could 
be a quota issue.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:37:15.313Z: 
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of workers to 9 based on 
the rate of progress in the currently running step(s).
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:37:15.348Z: 
JOB_MESSAGE_DETAILED: Resized worker pool to 9, though goal was 10.  This could 
be a quota issue.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:37:31.476Z: 
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of workers to 10 based on 
the rate of progress in the currently running step(s).
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:38:16.846Z: 
JOB_MESSAGE_DETAILED: Workers have started successfully.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:38:16.875Z: 
JOB_MESSAGE_DETAILED: Workers have started successfully.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:02.189Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/DoOnce/Read+write/Write/WriteImpl/InitializeWrite
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:02.269Z: 
JOB_MESSAGE_DEBUG: Value "write/Write/WriteImpl/DoOnce/Read.out" materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:02.308Z: 
JOB_MESSAGE_DEBUG: Value "write/Write/WriteImpl/InitializeWrite.out" 
materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:02.381Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/WriteBundles/_UnpickledSideInput(InitializeWrite.out.0)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:02.428Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(InitializeWrite.out.0)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:02.435Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/WriteBundles/_UnpickledSideInput(InitializeWrite.out.0)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:02.465Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(InitializeWrite.out.0)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:02.483Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(InitializeWrite.out.0)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:02.501Z: 
JOB_MESSAGE_DEBUG: Value 
"write/Write/WriteImpl/WriteBundles/_UnpickledSideInput(InitializeWrite.out.0).output"
 materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:02.520Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(InitializeWrite.out.0)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:02.576Z: 
JOB_MESSAGE_DEBUG: Value 
"write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(InitializeWrite.out.0).output"
 materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:02.614Z: 
JOB_MESSAGE_DEBUG: Value 
"write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(InitializeWrite.out.0).output"
 materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:42:29.063Z: 
JOB_MESSAGE_DETAILED: Checking permissions granted to controller Service 
Account.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:45:57.590Z: 
JOB_MESSAGE_BASIC: Finished operation 
read/Read+split+pair_with_one+group/Reify+group/Write
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:45:57.666Z: 
JOB_MESSAGE_BASIC: Executing operation group/Close
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:45:57.715Z: 
JOB_MESSAGE_BASIC: Finished operation group/Close
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:45:57.780Z: 
JOB_MESSAGE_BASIC: Executing operation 
group/Read+group/GroupByWindow+count+format+write/Write/WriteImpl/WriteBundles/WriteBundles+write/Write/WriteImpl/Pair+write/Write/WriteImpl/WindowInto(WindowIntoFn)+write/Write/WriteImpl/GroupByKey/Reify+write/Write/WriteImpl/GroupByKey/Write
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:48:29.052Z: 
JOB_MESSAGE_DETAILED: Checking permissions granted to controller Service 
Account.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:53.388Z: 
JOB_MESSAGE_BASIC: Finished operation 
group/Read+group/GroupByWindow+count+format+write/Write/WriteImpl/WriteBundles/WriteBundles+write/Write/WriteImpl/Pair+write/Write/WriteImpl/WindowInto(WindowIntoFn)+write/Write/WriteImpl/GroupByKey/Reify+write/Write/WriteImpl/GroupByKey/Write
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:53.460Z: 
JOB_MESSAGE_BASIC: Executing operation write/Write/WriteImpl/GroupByKey/Close
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:53.519Z: 
JOB_MESSAGE_BASIC: Finished operation write/Write/WriteImpl/GroupByKey/Close
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:53.586Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/GroupByKey/Read+write/Write/WriteImpl/GroupByKey/GroupByWindow+write/Write/WriteImpl/Extract
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:56.885Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/GroupByKey/Read+write/Write/WriteImpl/GroupByKey/GroupByWindow+write/Write/WriteImpl/Extract
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:56.946Z: 
JOB_MESSAGE_DEBUG: Value "write/Write/WriteImpl/Extract.out" materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:57.015Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(Extract.out.0)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:57.050Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(Extract.out.0)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:57.067Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(Extract.out.0)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:57.097Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(Extract.out.0)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:57.141Z: 
JOB_MESSAGE_DEBUG: Value 
"write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(Extract.out.0).output" 
materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:57.177Z: 
JOB_MESSAGE_DEBUG: Value 
"write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(Extract.out.0).output" 
materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:49:57.239Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/PreFinalize/PreFinalize
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:50:00.489Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/PreFinalize/PreFinalize
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:50:00.541Z: 
JOB_MESSAGE_DEBUG: Value "write/Write/WriteImpl/PreFinalize.out" materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:50:00.590Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:50:00.633Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0)
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:50:00.696Z: 
JOB_MESSAGE_DEBUG: Value 
"write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0).output"
 materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:50:00.751Z: 
JOB_MESSAGE_BASIC: Executing operation 
write/Write/WriteImpl/FinalizeWrite/FinalizeWrite
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:50:03.987Z: 
JOB_MESSAGE_BASIC: Finished operation 
write/Write/WriteImpl/FinalizeWrite/FinalizeWrite
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:50:04.040Z: 
JOB_MESSAGE_DEBUG: Executing success step success24
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:50:04.143Z: 
JOB_MESSAGE_DETAILED: Cleaning up.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:50:04.528Z: 
JOB_MESSAGE_DEBUG: Starting worker pool teardown.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:50:04.566Z: 
JOB_MESSAGE_BASIC: Stopping worker pool...
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:51:22.668Z: 
JOB_MESSAGE_DETAILED: Autoscaling: Resized worker pool from 10 to 0.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:51:22.720Z: 
JOB_MESSAGE_BASIC: Worker pool stopped.
INFO:apache_beam.runners.dataflow.dataflow_runner:2020-03-14T18:51:22.757Z: 
JOB_MESSAGE_DEBUG: Tearing down pending resources...
INFO:apache_beam.runners.dataflow.dataflow_runner:Job 
2020-03-14_11_35_25-12215412939888866684 is in state JOB_STATE_DONE
DEBUG:apache_beam.io.filesystem:Listing files in 
'gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results'
DEBUG:apache_beam.io.filesystem:translate_pattern: 
'gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results*-of-*'
 -> 
'gs\\:\\/\\/temp\\-storage\\-for\\-end\\-to\\-end\\-tests\\/py\\-it\\-cloud\\/output\\/1584210920604\\/results[^/\\\\]*\\-of\\-[^/\\\\]*'
INFO:apache_beam.io.gcp.gcsio:Starting the size estimation of the input
INFO:apache_beam.io.gcp.gcsio:Finished listing 37 files in 0.0773520469666 
seconds.
INFO:apache_beam.testing.pipeline_verifiers:Find 37 files in 
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results*-of-*:
 
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00019-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00031-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00015-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00027-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00026-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00010-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00016-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00021-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00024-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00000-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00029-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00008-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00002-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00007-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00033-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00006-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00022-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00034-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00030-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00035-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00023-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00028-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00036-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00003-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00020-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00012-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00013-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00025-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00009-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00014-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00032-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00011-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00017-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00005-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00004-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00018-of-00037
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1584210920604/results-00001-of-00037

IssueCommand timed out after 1200 seconds.  Process was killed by 
perfkitbenchmarker.
2020-03-14 18:54:10,899 ff6d8b16 MainThread beam_integration_benchmark(1/1) 
ERROR    Benchmark 1/1 beam_integration_benchmark (UID: 
beam_integration_benchmark0) failed. Execution will continue.
2020-03-14 18:54:10,900 ff6d8b16 MainThread beam_integration_benchmark(1/1) 
INFO     Benchmark run statuses:
---------------------------------------------------------------------------------
Name                        UID                          Status  Failed 
Substatus
---------------------------------------------------------------------------------
beam_integration_benchmark  beam_integration_benchmark0  FAILED                 
 
---------------------------------------------------------------------------------
Success rate: 0.00% (0/1)
2020-03-14 18:54:10,900 ff6d8b16 MainThread beam_integration_benchmark(1/1) 
INFO     Complete logs can be found at: 
<https://builds.apache.org/job/beam_PerformanceTests_WordCountIT_Py27/ws/runs/ff6d8b16/pkb.log>
2020-03-14 18:54:10,900 ff6d8b16 MainThread beam_integration_benchmark(1/1) 
INFO     Completion statuses can be found at: 
<https://builds.apache.org/job/beam_PerformanceTests_WordCountIT_Py27/ws/runs/ff6d8b16/completion_statuses.json>
Build step 'Execute shell' marked build as failure

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to