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

Changes:

[Kyle Weaver] [BEAM-9956] Remove unbalanced code markup.

[zyichi] Bump up advanceProcessingTime duration in ParDoTest

[Luke Cwik] [BEAM-10040, BEAM-6804] Increase wait times to reduce flakiness.

[noreply] [BEAM-10752] add use_deprecated_read experiment for testSimpleInsert,


------------------------------------------
[...truncated 289.19 KB...]
            "output_name": "out",
            "step_name": "SideInput-s20"
          }
        },
        "output_info": [
          {
            "encoding": {
              "@type": "kind:windowed_value",
              "component_encodings": [
                {
                  "@type": 
"FastPrimitivesCoder$QlpoOTFBWSZTWYQR6NMAAEDXwH8QgCEJAEBAv279AmAAIABqEqnqGgaABpoyNAGVHpGgMgDQBk0oR6IeoECBiqqU5NY23ndshzT2UPUOGrg42YPi9VyA8lbwwPJgtghxs5Qq1aWwExCDeMa0RHC2QigTCdizz1nx+LuSKcKEhCCPRpg=",
                  "component_encodings": [
                    {
                      "@type": 
"FastPrimitivesCoder$QlpoOTFBWSZTWYQR6NMAAEDXwH8QgCEJAEBAv279AmAAIABqEqnqGgaABpoyNAGVHpGgMgDQBk0oR6IeoECBiqqU5NY23ndshzT2UPUOGrg42YPi9VyA8lbwwPJgtghxs5Qq1aWwExCDeMa0RHC2QigTCdizz1nx+LuSKcKEhCCPRpg=",
                      "component_encodings": [],
                      "pipeline_proto_coder_id": 
"ref_Coder_FastPrimitivesCoder_5"
                    },
                    {
                      "@type": 
"FastPrimitivesCoder$QlpoOTFBWSZTWYQR6NMAAEDXwH8QgCEJAEBAv279AmAAIABqEqnqGgaABpoyNAGVHpGgMgDQBk0oR6IeoECBiqqU5NY23ndshzT2UPUOGrg42YPi9VyA8lbwwPJgtghxs5Qq1aWwExCDeMa0RHC2QigTCdizz1nx+LuSKcKEhCCPRpg=",
                      "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": 
"QlpoOTFBWSZTWclAGToACKN/////////////////////////////////////////////4AjT6gNtVYI2bIsbIBPYNROINFCGSmJ4jSPU0xHqPSbSepo09I0ZDQDTQNPUADQA0AAAANAAaAGgAAANADQ0AGgAaB6hoaDTQ0GQ02kGSCaBNNGmQjJomSPUNHqabRDTQaBoNA0aZAAAANDQNNAAAADQAAA0AAAAAAAAAAAAAJMABMAATAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAABB6n+qQYhMAI0xNMAEwRpkwEaaZMATAEYAJgAjABGmmJgBMAACNMAAmAACaYAAgwho0DAAkwAEwABMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAABIkQBAII2o1T8jVP0IIB6agaaeo9TJptIxAADQNNNAGQaPJqaAaAAA0NAAAAAAAAAAAAAAABl0xNjMGMCrLmxUZbknpwitNCJo69exgR3pp2C8yKBYtrp5R6SSG7u3PNZPAuqrHm6rTwv0URLrQxCA07y2aQRCAhQoEBI0xJtIBmfSO+zSXLJjGu6eEeqSmxjQlMQgnXsmgDFnMhYCgTxIRwFgPhfCiS1Uvq1u5cs3kV1d2s9z4CwzmAwmI6L/BqJHiDRCJwLVZqG2NqapxWTOGmWXFp0hFguOjQOeJl50h4ckFZhDD34qPE3BhtJlSKsZIO/07rMVBVOkmEwnBOQpWEphMCdZPx81OUBHP+Ajh9B0LbnOclKriRUm5s4kjTrhmDn6CU6YRddwrZ1ZTCEsMBGMlDkokSkNhM1CUkNjeUghsOLIUbkkMlpOaZDaZGM6+M5YnhNA2gUGmTLFkDSM5SVkysps6uFgQznIws5dUHXgkZkX/T0e33/+rd6iq97G12eIt/cX+S7HF7KQEswLe9L3ztCLdyGRcsJCAEBzfBlo4Sx12NtYRuJ3zOc5z/Q9L1PWvPPPPOa1jGMY1rWMWv/aR0WgY4JkAwbzmd6bpeRyWQ6Pf89rrlJHBLeVFQLpYmmrmFqyk5smJuhkLJw8VBfBgyRUXi4qMboCwDNZw5s5p8uwDXpLVwDzMItVXKCB0ai4KjfGc4S6Aroh1ZJyyu9ZllUjRTvH2BOBs5qDPldz3QKGUDXDLB+GSNEv7/jhemGrBYRcZEUKgtiiLZyomawoUQFNVigUzIuxHCg5DhrjCxTZsTPHEiZyoYLmu8hJHpHofJr/S7DCIrEsQ7vCilOjuvL/psmWmX9Vj5Tb6dUuBpbEmbMM0SVKgZx8fndTI65PZ1Db43kY6V7Xol/bMficqb4DMwYrLJlVn1q/IP5P5MkSS0iSihqzUoVkRjAtBqyTtJRIJApGuVSPsOq5llVRRr9qLBy+DKajQ0jeModbGd+XeZKIjXnhxrG51W7q5FxIaw2tyTgk0OSabVBJ3Q3qI4dGQx+kfKNBx13LX6ZTuOW7Sq+GXYhIgwY4JGpFxCQfhfiTiTOIIgUPERhrCWVd9BKBLzkOplmKaihuIulApNdZlUHGUzKCuKJXb+L8grFKouiTtJz3c8mqU8eVLrCqYVzHmU2gWXnLioovoBhdzdne0djTFYklWqgwiymRXi64CtYmzjVLQdAHOBqiFBLkNCnpl6eCJaRBkbZjK40DbPAshXXpdPIQqMxEAShMSSJMDu+j77dTEADbd6bUC0yDsgQeyrfQ6lj6dT3LPFoyXK5KNKk13WMdiiAVTnZKU6EmrJuRtGTcqZTVRAE3ANUIpcEwDiACiNCRcRNUkpF3BKjUO0Ehh7gpDozwVhs7KoTUsctcxyGsgNRdRbECYtAX0BAnneJrA4JpEGUPqhpmnFCFyokGNkxyNGYEQUJdMgL4Mp0M6SYS1EieOPK3FTYzIQVNNkOuoUKqbok3VUaaSWBf9stsJmMjfk7ePqKp4ZcgEVhika5TqT5kVNwttTmZS6CVsRMi7RaB0zQRy6gmBtGCg06SdixuLxU8iqLS4e2Cqml9FsG3At2p7w1pwtemqBu6llCsWgXKAlBvZitgmAaFhbpEpfGhAwDV7FITrpZ6iy3sWiGBiBLze6Vmocg7CGEEFALwGDWFX3G5qiZgpVz75mJlJp3IAmjShr6clAw1thak2WWgmIKZgYNc8D6GQsoDmb+E84DYBwAYFnAnJiadWgB/UC+bMPT2+Rdgfae2JsRRYaGA/AcldyJwLAUjkmrmcmMrt3ys66cwNDbNXNLKEZDLYKxn2pTYoGXWKB3i1lCIkNiYRxyarOQdqLGlJhYVDXEYhAtYQNTWxi2XrFgqFwynGQEGxaKGBF3zMsV1sRKV5N5l8bxLyklrBp0BrGhdqWU4wgVbFFzwDw279PRxVlVcNViTYJZmpqS0FaDJqYMpTbuKdKCYseEiw5QrVLXEVFvAvFfr7egKxoxNFNYXhQrixqaE6SCKIAwuQp+zwTE23cy2ClhJUtWiiHMtTGSaghW74xznpGtBvOY0hSw9z2GLapBG0Nk09MpM1armnYGAkoadhRCnu82WWk7OLTMRRmVKRbVuIy41Ybqyg2RFLVyMzNws6BYzoVLBDYNmvAUq/bxWlXGCNopV5GA2aJYm2mqlzXq+tUy0MZDUudh8nzsRFD7+qLWu/XZ6x9kL779Jj8DWy2USCZKcEEEBgkcxc6mrf35GIj0stNS0s+UxknuVX7ULajzWOiXdNU2GGymAkUKhTjUGUWHtTbGVDw8TCAMGEpWKAAAAAAAAAAAWAANAAIxYsmFDQIAEEEEEEbwWxJ6OSw0dG22qIrk223bbEnbbaW23XXU5pF9+WVN8Rc5ZcuKTbbbdFF8qpUSbbbtySlwlbOcRF+WUqZosYs2CRtcRRptKU0tzsa08CaziYxmqqzXNaAo+zq38U7N7hwgpCVhdulu82KdltQ64S6cwUVjjYVgqb8ZBzIaRpmdVC3ZS94ObGpb1dRwEli1Nnen5m+yQfgC4ZnCkC+MOgHSDisYHIMM05jM2qJhQquumK6Xbpi0F8ZoFgwZpBlezseFKDbDPSDOyhvMhjL5cc0phOWbZw0ZXCq+CmRehBCzWRQIfNdWNoltMFD9oOBVIYS4PipVquQJgmOlKJo5nKCDLxW2ue9iF2uhET8GKWKIcTcrE1qT0c5WAgflN9YE8QiQize+1nc7V+X6/51agjBqgmsvCmw5y2gC5iG2o11n4GokaiupJGFG141SRnq1SUq1uLDVKTJJbkdzhg2rSixhKoowm6VFNNdpUr96p2G+aGya+kxTWAjzdPDsKUMKJMGEhCALIQGfmcueQ8+qtNoDzxfn9vurMx8Pj8q/M/NdNLvp9ftNq/urY/n9Tk7+/7Pf6fdUEHdRBD81ZARgDgAEIMIwohAoAYBhIJBWAZgWe+0UT3+tSIDpCJn4p0jQO4n591aVoM7+HoUCb0Eog+9H7BkgbBpg2DTBsGL7kQG0B0kECkNANpIbbQP3MCObaDyrQqxpa3qf/i7kinChIZKAMnQA==",
        "user_name": "Write/Write/WriteImpl/FinalizeWrite/FinalizeWrite"
      }
    }
  ],
  "type": "JOB_TYPE_BATCH"
}
apache_beam.runners.dataflow.internal.apiclient: INFO: Create job: <Job
 createTime: '2020-08-19T00:48:38.506184Z'
 currentStateTime: '1970-01-01T00:00:00Z'
 id: '2020-08-18_17_48_37-11010586162024399458'
 location: 'us-central1'
 name: 'performance-tests-wordcount-python37-batch-1gb0819001625'
 projectId: 'apache-beam-testing'
 stageStates: []
 startTime: '2020-08-19T00:48:38.506184Z'
 steps: []
 tempFiles: []
 type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)>
apache_beam.runners.dataflow.internal.apiclient: INFO: Created job with id: 
[2020-08-18_17_48_37-11010586162024399458]
apache_beam.runners.dataflow.internal.apiclient: INFO: Submitted job: 
2020-08-18_17_48_37-11010586162024399458
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-08-18_17_48_37-11010586162024399458?project=apache-beam-testing
apache_beam.runners.dataflow.dataflow_runner: INFO: Job 
2020-08-18_17_48_37-11010586162024399458 is in state JOB_STATE_RUNNING
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:41.262Z: 
JOB_MESSAGE_BASIC: Worker configuration: n1-standard-1 in us-central1-a.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:41.962Z: 
JOB_MESSAGE_DETAILED: Expanding CoGroupByKey operations into optimizable parts.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:41.995Z: 
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-08-19T00:48:42.063Z: 
JOB_MESSAGE_DETAILED: Expanding GroupByKey operations into optimizable parts.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.094Z: 
JOB_MESSAGE_DETAILED: Lifting ValueCombiningMappingFns into 
MergeBucketsMappingFns
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.198Z: 
JOB_MESSAGE_DEBUG: Annotating graph with Autotuner information.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.245Z: 
JOB_MESSAGE_DETAILED: Fusing adjacent ParDo, Read, Write, and Flatten operations
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.278Z: 
JOB_MESSAGE_DETAILED: Fusing consumer Split into Read/Read
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.311Z: 
JOB_MESSAGE_DETAILED: Fusing consumer PairWIthOne into Split
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.347Z: 
JOB_MESSAGE_DETAILED: Fusing consumer 
GroupAndSum/GroupByKey+GroupAndSum/Combine/Partial into PairWIthOne
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.380Z: 
JOB_MESSAGE_DETAILED: Fusing consumer GroupAndSum/GroupByKey/Reify into 
GroupAndSum/GroupByKey+GroupAndSum/Combine/Partial
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.415Z: 
JOB_MESSAGE_DETAILED: Fusing consumer GroupAndSum/GroupByKey/Write into 
GroupAndSum/GroupByKey/Reify
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.474Z: 
JOB_MESSAGE_DETAILED: Fusing consumer GroupAndSum/Combine into 
GroupAndSum/GroupByKey/Read
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.509Z: 
JOB_MESSAGE_DETAILED: Fusing consumer GroupAndSum/Combine/Extract into 
GroupAndSum/Combine
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.546Z: 
JOB_MESSAGE_DETAILED: Fusing consumer Format into GroupAndSum/Combine/Extract
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.572Z: 
JOB_MESSAGE_DETAILED: Fusing consumer 
Write/Write/WriteImpl/WindowInto(WindowIntoFn) into Format
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.604Z: 
JOB_MESSAGE_DETAILED: Fusing consumer 
Write/Write/WriteImpl/WriteBundles/WriteBundles into 
Write/Write/WriteImpl/WindowInto(WindowIntoFn)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.637Z: 
JOB_MESSAGE_DETAILED: Fusing consumer Write/Write/WriteImpl/Pair into 
Write/Write/WriteImpl/WriteBundles/WriteBundles
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.678Z: 
JOB_MESSAGE_DETAILED: Fusing consumer Write/Write/WriteImpl/GroupByKey/Reify 
into Write/Write/WriteImpl/Pair
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.715Z: 
JOB_MESSAGE_DETAILED: Fusing consumer Write/Write/WriteImpl/GroupByKey/Write 
into Write/Write/WriteImpl/GroupByKey/Reify
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.752Z: 
JOB_MESSAGE_DETAILED: Fusing consumer 
Write/Write/WriteImpl/GroupByKey/GroupByWindow into 
Write/Write/WriteImpl/GroupByKey/Read
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.789Z: 
JOB_MESSAGE_DETAILED: Fusing consumer Write/Write/WriteImpl/Extract into 
Write/Write/WriteImpl/GroupByKey/GroupByWindow
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.821Z: 
JOB_MESSAGE_DETAILED: Fusing consumer Write/Write/WriteImpl/InitializeWrite 
into Write/Write/WriteImpl/DoOnce/Read
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.857Z: 
JOB_MESSAGE_DEBUG: Workflow config is missing a default resource spec.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.892Z: 
JOB_MESSAGE_DEBUG: Adding StepResource setup and teardown to workflow graph.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.925Z: 
JOB_MESSAGE_DEBUG: Adding workflow start and stop steps.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:42.961Z: 
JOB_MESSAGE_DEBUG: Assigning stage ids.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:43.381Z: 
JOB_MESSAGE_DEBUG: Executing wait step start35
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:43.453Z: 
JOB_MESSAGE_BASIC: Executing operation 
Write/Write/WriteImpl/DoOnce/Read+Write/Write/WriteImpl/InitializeWrite
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:43.486Z: 
JOB_MESSAGE_BASIC: Executing operation Write/Write/WriteImpl/GroupByKey/Create
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:43.497Z: 
JOB_MESSAGE_DEBUG: Starting **** pool setup.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:43.522Z: 
JOB_MESSAGE_BASIC: Executing operation GroupAndSum/GroupByKey/Create
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:43.533Z: 
JOB_MESSAGE_BASIC: Starting 10 ****s in us-central1-a...
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:43.595Z: 
JOB_MESSAGE_BASIC: Finished operation GroupAndSum/GroupByKey/Create
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:43.595Z: 
JOB_MESSAGE_BASIC: Finished operation Write/Write/WriteImpl/GroupByKey/Create
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:43.664Z: 
JOB_MESSAGE_DEBUG: Value "Write/Write/WriteImpl/GroupByKey/Session" 
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:43.690Z: 
JOB_MESSAGE_DEBUG: Value "GroupAndSum/GroupByKey/Session" materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:48:43.754Z: 
JOB_MESSAGE_BASIC: Executing operation 
Read/Read+Split+PairWIthOne+GroupAndSum/GroupByKey+GroupAndSum/Combine/Partial+GroupAndSum/GroupByKey/Reify+GroupAndSum/GroupByKey/Write
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:49:02.060Z: 
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-08-19T00:49:08.326Z: 
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of ****s to 9 based on the 
rate of progress in the currently running stage(s).
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:49:08.367Z: 
JOB_MESSAGE_DETAILED: Resized **** pool to 9, though goal was 10.  This could 
be a quota issue.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:49:13.734Z: 
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-08-19T00:50:43.235Z: 
JOB_MESSAGE_DETAILED: Workers have started successfully.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:50:43.273Z: 
JOB_MESSAGE_DETAILED: Workers have started successfully.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:53:53.557Z: 
JOB_MESSAGE_BASIC: Finished operation 
Write/Write/WriteImpl/DoOnce/Read+Write/Write/WriteImpl/InitializeWrite
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:53:53.643Z: 
JOB_MESSAGE_DEBUG: Value "Write/Write/WriteImpl/DoOnce/Read.out" materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:53:53.684Z: 
JOB_MESSAGE_DEBUG: Value "Write/Write/WriteImpl/InitializeWrite.out" 
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:53:53.766Z: 
JOB_MESSAGE_BASIC: Executing operation 
Write/Write/WriteImpl/WriteBundles/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:53:53.802Z: 
JOB_MESSAGE_BASIC: Executing operation 
Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:53:53.840Z: 
JOB_MESSAGE_BASIC: Finished operation 
Write/Write/WriteImpl/WriteBundles/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:53:53.844Z: 
JOB_MESSAGE_BASIC: Executing operation 
Write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:53:53.849Z: 
JOB_MESSAGE_BASIC: Finished operation 
Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:53:53.905Z: 
JOB_MESSAGE_BASIC: Finished operation 
Write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:53:53.930Z: 
JOB_MESSAGE_DEBUG: Value 
"Write/Write/WriteImpl/WriteBundles/_UnpickledSideInput(InitializeWrite.out.0).output"
 materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:53:53.967Z: 
JOB_MESSAGE_DEBUG: Value 
"Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(InitializeWrite.out.0).output"
 materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:53:54Z: 
JOB_MESSAGE_DEBUG: Value 
"Write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(InitializeWrite.out.0).output"
 materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:56:14.878Z: 
JOB_MESSAGE_BASIC: Finished operation 
Read/Read+Split+PairWIthOne+GroupAndSum/GroupByKey+GroupAndSum/Combine/Partial+GroupAndSum/GroupByKey/Reify+GroupAndSum/GroupByKey/Write
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:56:14.950Z: 
JOB_MESSAGE_BASIC: Executing operation GroupAndSum/GroupByKey/Close
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:56:15Z: 
JOB_MESSAGE_BASIC: Finished operation GroupAndSum/GroupByKey/Close
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:56:15.066Z: 
JOB_MESSAGE_BASIC: Executing operation 
GroupAndSum/GroupByKey/Read+GroupAndSum/Combine+GroupAndSum/Combine/Extract+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-08-19T00:58:53.063Z: 
JOB_MESSAGE_BASIC: Finished operation 
GroupAndSum/GroupByKey/Read+GroupAndSum/Combine+GroupAndSum/Combine/Extract+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-08-19T00:58:53.127Z: 
JOB_MESSAGE_BASIC: Executing operation Write/Write/WriteImpl/GroupByKey/Close
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:53.185Z: 
JOB_MESSAGE_BASIC: Finished operation Write/Write/WriteImpl/GroupByKey/Close
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:53.274Z: 
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-08-19T00:58:55.799Z: 
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-08-19T00:58:55.882Z: 
JOB_MESSAGE_DEBUG: Value "Write/Write/WriteImpl/Extract.out" materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:55.954Z: 
JOB_MESSAGE_BASIC: Executing operation 
Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(Extract.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:55.994Z: 
JOB_MESSAGE_BASIC: Executing operation 
Write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(Extract.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:56.002Z: 
JOB_MESSAGE_BASIC: Finished operation 
Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(Extract.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:56.052Z: 
JOB_MESSAGE_BASIC: Finished operation 
Write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(Extract.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:56.061Z: 
JOB_MESSAGE_DEBUG: Value 
"Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(Extract.out.0).output" 
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:56.124Z: 
JOB_MESSAGE_DEBUG: Value 
"Write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(Extract.out.0).output" 
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:56.200Z: 
JOB_MESSAGE_BASIC: Executing operation 
Write/Write/WriteImpl/PreFinalize/PreFinalize
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:58.651Z: 
JOB_MESSAGE_BASIC: Finished operation 
Write/Write/WriteImpl/PreFinalize/PreFinalize
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:58.726Z: 
JOB_MESSAGE_DEBUG: Value "Write/Write/WriteImpl/PreFinalize.out" materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:58.816Z: 
JOB_MESSAGE_BASIC: Executing operation 
Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:58.869Z: 
JOB_MESSAGE_BASIC: Finished operation 
Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:58.936Z: 
JOB_MESSAGE_DEBUG: Value 
"Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0).output"
 materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:58:58.999Z: 
JOB_MESSAGE_BASIC: Executing operation 
Write/Write/WriteImpl/FinalizeWrite/FinalizeWrite
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:59:01.363Z: 
JOB_MESSAGE_BASIC: Finished operation 
Write/Write/WriteImpl/FinalizeWrite/FinalizeWrite
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:59:01.485Z: 
JOB_MESSAGE_DEBUG: Executing success step success33
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:59:01.581Z: 
JOB_MESSAGE_DETAILED: Cleaning up.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:59:01.704Z: 
JOB_MESSAGE_DEBUG: Starting **** pool teardown.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T00:59:01.742Z: 
JOB_MESSAGE_BASIC: Stopping **** pool...
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T01:00:03.882Z: 
JOB_MESSAGE_DETAILED: Autoscaling: Resized **** pool from 10 to 0.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T01:00:03.933Z: 
JOB_MESSAGE_BASIC: Worker pool stopped.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-19T01:00:03.970Z: 
JOB_MESSAGE_DEBUG: Tearing down pending resources...
apache_beam.runners.dataflow.dataflow_runner: INFO: Job 
2020-08-18_17_48_37-11010586162024399458 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/1597798110975/results'
apache_beam.io.filesystem: DEBUG: translate_pattern: 
'gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results*-of-*'
 -> 
'gs://temp\\-storage\\-for\\-end\\-to\\-end\\-tests/py\\-it\\-cloud/output/1597798110975/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.0531773567199707 
seconds.
apache_beam.testing.pipeline_verifiers: INFO: Find 30 files in 
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results*-of-*:
 
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00000-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00001-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00002-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00003-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00004-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00005-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00006-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00007-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00008-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00009-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00010-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00011-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00012-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00013-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00014-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00015-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00016-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00017-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00018-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00019-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00020-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00021-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00022-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00023-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00024-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00025-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00026-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00027-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00028-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results-00029-of-00030
apache_beam.testing.pipeline_verifiers: INFO: Read from given path 
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results*-of-*,
 26186927 lines, checksum: ea0ca2e5ee4ea5f218790f28d0b9fe7d09d8d710.
google.auth._default: DEBUG: Checking None for explicit credentials as part of 
auth process...
google.auth._default: DEBUG: Checking Cloud SDK credentials as part of auth 
process...
google.auth._default: DEBUG: Cloud SDK credentials not found on disk; not using 
them
google.auth._default: DEBUG: Checking for App Engine runtime as part of auth 
process...
google.auth._default: DEBUG: No App Engine library was found so cannot 
authentication via App Engine Identity Credentials.
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 221
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_py37_pkb_results
 HTTP/1.1" 200 None
apache_beam.testing.load_tests.load_test_metrics_utils: INFO: Load test results 
for test: 3cf4f83ecc9142aa9e445b4109efcc1e and timestamp: 1597798908.3287728:
apache_beam.testing.load_tests.load_test_metrics_utils: INFO: Metric: runtime 
Value: 797
urllib3.connectionpool: DEBUG: https://bigquery.googleapis.com:443 "POST 
/bigquery/v2/projects/apache-beam-testing/datasets/beam_performance/tables/wordcount_py37_pkb_results/insertAll
 HTTP/1.1" 200 None
apache_beam.testing.load_tests.load_test_metrics_utils: ERROR: no such field.
apache_beam.io.filesystem: DEBUG: Listing files in 
'gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results'
apache_beam.io.filesystem: DEBUG: translate_pattern: 
'gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1597798110975/results*'
 -> 
'gs://temp\\-storage\\-for\\-end\\-to\\-end\\-tests/py\\-it\\-cloud/output/1597798110975/results[^/\\\\]*'
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.05283808708190918 seconds.
--------------------- >> end captured logging << ---------------------

----------------------------------------------------------------------
XML: nosetests-runPerformanceTest-df-py37.xml
----------------------------------------------------------------------
XML: 
<https://ci-beam.apache.org/job/beam_PerformanceTests_WordCountIT_Py37/ws/src/sdks/python/nosetests.xml>
----------------------------------------------------------------------
Ran 1 test in 797.830s

FAILED (errors=1)

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

FAILURE: Build failed with an exception.

* What went wrong:
Execution failed for task 
':sdks:python:test-suites:dataflow:py37: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 7s
5 actionable tasks: 5 executed

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

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