See
<https://ci-beam.apache.org/job/beam_PerformanceTests_WordCountIT_Py37/1842/display/redirect?page=changes>
Changes:
[Robin Qiu] Propagate BigQuery streaming insert throttled time to Dataflow
worker in
[noreply] Merge pull request #12415 from [BEAM-10603] Add the RecordingManager
and
------------------------------------------
[...truncated 292.31 KB...]
"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":
"QlpoOTFBWSZTWeKoJmUACKN/////////////////////////////////////////////4AjT6gN24uCNm0LGyCk9g1E4g0UIZKYniNI9TTEeo9JtJ6nqNMjJkNANNBk9QAaAAAAAANAAaAGgAAANA0A0AGgAaB6hoaDTQ0Gg02kGSEaBMTBTIyaE0j1DR6mm1AAAAyaNGmQAAAAAaZPUAAAAAAAAAAAAAAAAAAAAAGSNigAAAATAJgARgBMjAACYAJgAEGBMRgEwmTAAAAAAAAATAmjIwIMAAAJoQep/qkGITACNMTTABMEaZMBGmmTAEwBGACYAIwARppiYATAAAjTAAJgAAmmAAIMIaNAwAJMABMAATAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAASJE0CEwgjI1T8plP0JHqNB4oHqZqPUyaYjEAANANNA0ZGmmynqaAaAAA0NAAAAAAAAAAAAANAAGXTG2N1MQE0uaFdluOmSAUTwCeK6urGDFfGSwXWQvrFtcmUdjjgvLxrrjKX1zK0Q3dFMD1VUK6IIQ+ZL22awRCAhQoEBI0xJtIBmponf5pLlUxjXdugeqSmxjQlMQgnXMigDFneQsBQJ4cI0CwHgvgxHar39Fu5cs3sN3eWs31MJaahhMRnHRf4NNI8SaAROBarNM2hszVOLyagaRbeXHSEWi3sZ5zpMwHSHiCQWGIMXgCs8VeGK4mVorxkg8DTcZisK50JhMJwTkKVpKYTAnYT8hNTlARz/go4fhehbc5zkpV8SK03NnEkaVkMw8/USnSEX38K6deUxBLFARjJQ5KJEpDYTNMlJDY3lIIbDpxCjbEhktFzTIbTIxnYRnKk8RnmyCo0iZasgaJqFCwmWFLetBaEM5qMTOWVR2AJGZGD6mh3HB/6tXiLrvrbXR4jH9xfpPr8TsJAS7At50fd+qEW6kcg5YSMAIDmd/K3kS51y32kIx4j2S0xNztDS1IYYYYYsEECCCCCEEECCJE944IRCUBDAFEcAEB79PqCSkxRR+j4HO665KRyZdygqhdNE01ewuWUnNkxN1MhZOHnVF4F/USoulpUYmvlgFzmTmjmHi3GHFrLczK8MsVi2DEsXiWjkirQg1y2IS12tKpmKqdIzQZysVFIwmTKKCINrULWV3HcgoZQONGWD0EcSJg4PGC/MEz9hFxEQwKC2KIuHKidxhUogKV2qBTMi7IcKDjeGt2WqbNeZ48kTOUDDe13wJI9E9B49d6HYYNFYlh3d4UUb3nrTH5pqZaVf1GPkOF0ypcDS2BM2YZokqVAzkJDOaiS1qeyqG2xkpjZbs+eY9kz952pvgMzBisMmVVfWr8g/k/kyRJLRpKKGrNShRqNkC0GrJO0FFAkCkq3DyFg1PLsiqLNftBX+TwJXT6CkbtlELY7vTDzJxNweeFb2Nyql2VyTiR1ZtbgnBJocc02qCTuhvERwqMhjtG+UZvd7qWu0qnbcwjSqeCYYhIgwY4JGpFwyQfhfSTijOJIgURExxrCWVZ9BKBLzsOtludNRU3EXygUmuOlWHT6TKiyKpX8CMGQVqlWXxJ3E57meTVKb8rXW1WwsmPMptAsvNXlZVgqBhfzNvNo7KmK1JKxVhiFlMiwC68CuYmzdqWe6gNaDMIEEujZ5TIXaX4VpCGROTmVpqG1eBZCuuS7dQhURhIAlCYkkSYHc8/22ymHAG17s4oFpkHZAg9lWuf07F0qnuGeKRk+Rx0aVyl3VMdeiAQ/NyMrz+UrKEI2jKEKmU1UQBQgBqhFrgmAcSAURnyLyJqhKRfyZWaZ2okMPclA6M8HabGwqk1LHLXMUZoQGuuutiBMegLyAgU0uk0A4RrEGUPKgkMkMBLG3bvMW9dRzZeQY+OtVgsa4xb9XKpu+i6wpVqKsCxIFSlsOysUKubqk3XWaSSWFf9tutJmMjgE7t/WVzxS40IsDOka5TTnzAqXi2lMzKOoldETIv0GgdJoI5ZQTA2TBQaVCdqxuMAqceqri8e0CulH0W3NqBbtU3popC16YoG8r2UKxaBaoCUG+nK2CcBwLC2xpS+LBhgGr2KQnXCztFl/WtEMDECXl9stNxBB2EMIIKAXYMGsKvj7iqJmClnPumoqVm3cgCaNKGvpyUDDWuFqTZZZiYgpqBbrngfMyEaA5nPfPOA2AcAGBZQJ2Zm3VmAf1AvnDD09vkZX32ntibEUYGhgPwHIX8ecG0FI5Bq9nIjLLt8revnLjQ2zVzSyhGQy2isZxqU+OBlydQ7xayhEKGxOIoo9VpIO0FjhScWFQXQiEAFrDBr6yMey+YsFQuGU5B8g2LRQvou/ZliujAlK8i6y/N6mAoS1g0qg1jPv07aYwgVjFF7wjxXcBPQzrCuyGrBJsEszU1JZ6uBk1MGUTbvKaMExY8RFpyRYqNcRVXcHALBr7WoLBozmilgYAqV5a1NCdCCKoAxOQp+0wzE23ey6CjCSo1cKIcy5MZJqCFdvjHOeia0G65fRFLF3XbsWzSCNkbBpaRQzWF5hR8CsFlSFHw4oswp5l1I/nRQSBycukiBuAqaUvm4tqNgRRq9GZnFLUQLGdCpYYbBs14CiwbWLErIwxslKzIwGzQLU201Wu7+t65SWfjIalzkPkeciIqee1Ra14xdnrHmQPPPVmPPuOS2UR+dJH333y/G1i6VOLezCMRHZZZFLSz5TGR+5M9agcruuMbCu7cU5BBZTBSGBQkiUGVWHdTciKh4eJhAGDCUrFAAAAAAAAAAALAAGgAEYsWTChoEACCCCCCN4bok9DJaaGhddXEWSbbbuuiTuuuLrr776ZpGDBllTBEXuWXLnSbbbbqqwSrlVJttu7JKXCV05xEYMspUmi1izYZGzzirSaUppbbb608Ka1BMYzVVhrmtAVfa1cHFu3eYsQKQlaX7hbnNnTturHZCXGMFFg426tFTBjIOYDRNI1FWLclHuhzY1LeLqmEks7T2N4fnb7NB+ELhnaKwLwxKgdFOOyAagxDJOZ3JhOKlXS5BdJeNnLgLwzQLDhzSDK9jb+GKjaDPSjOzhushjMEt60phOWbYxVZWiZ4FNLdgBCzQigQ8bpWf1S2mCp60GgqjMBaHhXmmagTCMVaUTxTtUEGXhtuNd9iButhET8GKWKoMbcoJorOxUlYCB6U39gUwiFCLN97XYdfN8v1/zkqiMKYE0Lw58SkuVAXMBy5GutTg6aRpq+hIxI2e7UkaleqSlYuKtNUoZJLbDvcMG1cVWsJVlWI3CqpSy4rWDeKdpvmhsmvpsU1gI81Tw7ClDCDhgwkIMBZBgz8rlzKPPprLaA80Z5vZ7axM+/4fGvTXyXTa75/T6ziv7K2P4/M7+v3/Z7/T7qgb3MQQ/FVQEcA4ABBuEYUQYUAMAwkEgrAMwLLfKKJ7/WhEB0hFT8W6RoHcT8+6s60Gd+D0KBN6CUQffj9oyQNg0wbBpg2DF92IDZA6EECkNANpIbbQP3UCOZaDyzQrBpa3qv/i7kinChIcVQTMo",
"user_name": "Write/Write/WriteImpl/FinalizeWrite/FinalizeWrite"
}
}
],
"type": "JOB_TYPE_BATCH"
}
apache_beam.runners.dataflow.internal.apiclient: INFO: Create job: <Job
createTime: '2020-08-25T00:46:34.523788Z'
currentStateTime: '1970-01-01T00:00:00Z'
id: '2020-08-24_17_46_33-8733151788777511898'
location: 'us-central1'
name: 'performance-tests-wordcount-python37-batch-1gb0825000824'
projectId: 'apache-beam-testing'
stageStates: []
startTime: '2020-08-25T00:46:34.523788Z'
steps: []
tempFiles: []
type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)>
apache_beam.runners.dataflow.internal.apiclient: INFO: Created job with id:
[2020-08-24_17_46_33-8733151788777511898]
apache_beam.runners.dataflow.internal.apiclient: INFO: Submitted job:
2020-08-24_17_46_33-8733151788777511898
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-24_17_46_33-8733151788777511898?project=apache-beam-testing
apache_beam.runners.dataflow.dataflow_runner: INFO: Job
2020-08-24_17_46_33-8733151788777511898 is in state JOB_STATE_RUNNING
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:37.404Z:
JOB_MESSAGE_BASIC: Worker configuration: n1-standard-1 in us-central1-f.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:38.864Z:
JOB_MESSAGE_DETAILED: Expanding CoGroupByKey operations into optimizable parts.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:38.902Z:
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-25T00:46:38.974Z:
JOB_MESSAGE_DETAILED: Expanding GroupByKey operations into optimizable parts.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.015Z:
JOB_MESSAGE_DETAILED: Lifting ValueCombiningMappingFns into
MergeBucketsMappingFns
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.109Z:
JOB_MESSAGE_DEBUG: Annotating graph with Autotuner information.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.151Z:
JOB_MESSAGE_DETAILED: Fusing adjacent ParDo, Read, Write, and Flatten operations
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.173Z:
JOB_MESSAGE_DETAILED: Fusing consumer Split into Read/Read
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.199Z:
JOB_MESSAGE_DETAILED: Fusing consumer PairWIthOne into Split
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.233Z:
JOB_MESSAGE_DETAILED: Fusing consumer
GroupAndSum/GroupByKey+GroupAndSum/Combine/Partial into PairWIthOne
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.260Z:
JOB_MESSAGE_DETAILED: Fusing consumer GroupAndSum/GroupByKey/Reify into
GroupAndSum/GroupByKey+GroupAndSum/Combine/Partial
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.295Z:
JOB_MESSAGE_DETAILED: Fusing consumer GroupAndSum/GroupByKey/Write into
GroupAndSum/GroupByKey/Reify
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.330Z:
JOB_MESSAGE_DETAILED: Fusing consumer GroupAndSum/Combine into
GroupAndSum/GroupByKey/Read
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.364Z:
JOB_MESSAGE_DETAILED: Fusing consumer GroupAndSum/Combine/Extract into
GroupAndSum/Combine
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.401Z:
JOB_MESSAGE_DETAILED: Fusing consumer Format into GroupAndSum/Combine/Extract
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.423Z:
JOB_MESSAGE_DETAILED: Fusing consumer
Write/Write/WriteImpl/WindowInto(WindowIntoFn) into Format
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.457Z:
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-25T00:46:39.481Z:
JOB_MESSAGE_DETAILED: Fusing consumer Write/Write/WriteImpl/Pair into
Write/Write/WriteImpl/WriteBundles/WriteBundles
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.517Z:
JOB_MESSAGE_DETAILED: Fusing consumer Write/Write/WriteImpl/GroupByKey/Reify
into Write/Write/WriteImpl/Pair
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.541Z:
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-25T00:46:39.572Z:
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-25T00:46:39.608Z:
JOB_MESSAGE_DETAILED: Fusing consumer Write/Write/WriteImpl/Extract into
Write/Write/WriteImpl/GroupByKey/GroupByWindow
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.641Z:
JOB_MESSAGE_DETAILED: Fusing consumer Write/Write/WriteImpl/InitializeWrite
into Write/Write/WriteImpl/DoOnce/Read
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.670Z:
JOB_MESSAGE_DEBUG: Workflow config is missing a default resource spec.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.722Z:
JOB_MESSAGE_DEBUG: Adding StepResource setup and teardown to workflow graph.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.751Z:
JOB_MESSAGE_DEBUG: Adding workflow start and stop steps.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:39.782Z:
JOB_MESSAGE_DEBUG: Assigning stage ids.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:40Z:
JOB_MESSAGE_DEBUG: Executing wait step start35
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:40.065Z:
JOB_MESSAGE_BASIC: Executing operation
Write/Write/WriteImpl/DoOnce/Read+Write/Write/WriteImpl/InitializeWrite
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:40.101Z:
JOB_MESSAGE_BASIC: Executing operation Write/Write/WriteImpl/GroupByKey/Create
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:40.112Z:
JOB_MESSAGE_DEBUG: Starting **** pool setup.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:40.134Z:
JOB_MESSAGE_BASIC: Executing operation GroupAndSum/GroupByKey/Create
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:40.144Z:
JOB_MESSAGE_BASIC: Starting 10 ****s in us-central1-f...
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:40.202Z:
JOB_MESSAGE_BASIC: Finished operation Write/Write/WriteImpl/GroupByKey/Create
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:40.202Z:
JOB_MESSAGE_BASIC: Finished operation GroupAndSum/GroupByKey/Create
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:40.256Z:
JOB_MESSAGE_DEBUG: Value "GroupAndSum/GroupByKey/Session" materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:40.289Z:
JOB_MESSAGE_DEBUG: Value "Write/Write/WriteImpl/GroupByKey/Session"
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:46:40.326Z:
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-25T00:46:47.616Z:
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-25T00:47:14.934Z:
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of ****s to 6 based on the
rate of progress in the currently running stage(s).
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:47:14.964Z:
JOB_MESSAGE_DETAILED: Resized **** pool to 6, though goal was 10. This could
be a quota issue.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:47:20.410Z:
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of ****s to 7 based on the
rate of progress in the currently running stage(s).
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:47:20.437Z:
JOB_MESSAGE_DETAILED: Resized **** pool to 7, though goal was 10. This could
be a quota issue.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:47:25.992Z:
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-25T00:47:26.022Z:
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-25T00:48:15.028Z:
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-25T00:48:51.335Z:
JOB_MESSAGE_DETAILED: Workers have started successfully.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:48:51.371Z:
JOB_MESSAGE_DETAILED: Workers have started successfully.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:52:09.696Z:
JOB_MESSAGE_BASIC: Finished operation
Write/Write/WriteImpl/DoOnce/Read+Write/Write/WriteImpl/InitializeWrite
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:52:09.757Z:
JOB_MESSAGE_DEBUG: Value "Write/Write/WriteImpl/DoOnce/Read.out" materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:52:09.778Z:
JOB_MESSAGE_DEBUG: Value "Write/Write/WriteImpl/InitializeWrite.out"
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:52:09.847Z:
JOB_MESSAGE_BASIC: Executing operation
Write/Write/WriteImpl/WriteBundles/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:52:09.870Z:
JOB_MESSAGE_BASIC: Executing operation
Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:52:09.896Z:
JOB_MESSAGE_BASIC: Finished operation
Write/Write/WriteImpl/WriteBundles/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:52:09.897Z:
JOB_MESSAGE_BASIC: Executing operation
Write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:52:09.912Z:
JOB_MESSAGE_BASIC: Finished operation
Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:52:09.945Z:
JOB_MESSAGE_BASIC: Finished operation
Write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(InitializeWrite.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:52:09.954Z:
JOB_MESSAGE_DEBUG: Value
"Write/Write/WriteImpl/WriteBundles/_UnpickledSideInput(InitializeWrite.out.0).output"
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:52:09.986Z:
JOB_MESSAGE_DEBUG: Value
"Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(InitializeWrite.out.0).output"
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:52:10.011Z:
JOB_MESSAGE_DEBUG: Value
"Write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(InitializeWrite.out.0).output"
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:54:53.216Z:
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-25T00:54:53.293Z:
JOB_MESSAGE_BASIC: Executing operation GroupAndSum/GroupByKey/Close
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:54:53.351Z:
JOB_MESSAGE_BASIC: Finished operation GroupAndSum/GroupByKey/Close
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:54:53.412Z:
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-25T00:57:42.533Z:
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-25T00:57:42.594Z:
JOB_MESSAGE_BASIC: Executing operation Write/Write/WriteImpl/GroupByKey/Close
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:42.642Z:
JOB_MESSAGE_BASIC: Finished operation Write/Write/WriteImpl/GroupByKey/Close
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:42.698Z:
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-25T00:57:44.918Z:
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-25T00:57:44.978Z:
JOB_MESSAGE_DEBUG: Value "Write/Write/WriteImpl/Extract.out" materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:45.082Z:
JOB_MESSAGE_BASIC: Executing operation
Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(Extract.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:45.106Z:
JOB_MESSAGE_BASIC: Executing operation
Write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(Extract.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:45.126Z:
JOB_MESSAGE_BASIC: Finished operation
Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(Extract.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:45.154Z:
JOB_MESSAGE_BASIC: Finished operation
Write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(Extract.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:45.192Z:
JOB_MESSAGE_DEBUG: Value
"Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(Extract.out.0).output"
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:45.228Z:
JOB_MESSAGE_DEBUG: Value
"Write/Write/WriteImpl/PreFinalize/_UnpickledSideInput(Extract.out.0).output"
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:45.310Z:
JOB_MESSAGE_BASIC: Executing operation
Write/Write/WriteImpl/PreFinalize/PreFinalize
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:47.679Z:
JOB_MESSAGE_BASIC: Finished operation
Write/Write/WriteImpl/PreFinalize/PreFinalize
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:47.751Z:
JOB_MESSAGE_DEBUG: Value "Write/Write/WriteImpl/PreFinalize.out" materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:47.809Z:
JOB_MESSAGE_BASIC: Executing operation
Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:47.880Z:
JOB_MESSAGE_BASIC: Finished operation
Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0)
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:47.929Z:
JOB_MESSAGE_DEBUG: Value
"Write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0).output"
materialized.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:47.986Z:
JOB_MESSAGE_BASIC: Executing operation
Write/Write/WriteImpl/FinalizeWrite/FinalizeWrite
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:50.703Z:
JOB_MESSAGE_BASIC: Finished operation
Write/Write/WriteImpl/FinalizeWrite/FinalizeWrite
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:50.768Z:
JOB_MESSAGE_DEBUG: Executing success step success33
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:50.852Z:
JOB_MESSAGE_DETAILED: Cleaning up.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:50.953Z:
JOB_MESSAGE_DEBUG: Starting **** pool teardown.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:57:50.986Z:
JOB_MESSAGE_BASIC: Stopping **** pool...
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:58:42.066Z:
JOB_MESSAGE_DETAILED: Autoscaling: Resized **** pool from 10 to 0.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:58:42.106Z:
JOB_MESSAGE_BASIC: Worker pool stopped.
apache_beam.runners.dataflow.dataflow_runner: INFO: 2020-08-25T00:58:42.133Z:
JOB_MESSAGE_DEBUG: Tearing down pending resources...
apache_beam.runners.dataflow.dataflow_runner: INFO: Job
2020-08-24_17_46_33-8733151788777511898 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/1598316390786/results'
apache_beam.io.filesystem: DEBUG: translate_pattern:
'gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results*-of-*'
->
'gs://temp\\-storage\\-for\\-end\\-to\\-end\\-tests/py\\-it\\-cloud/output/1598316390786/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.07180428504943848 seconds.
apache_beam.testing.pipeline_verifiers: INFO: Find 30 files in
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results*-of-*:
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00000-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00001-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00002-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00003-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00004-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00005-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00006-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00007-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00008-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00009-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00010-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00011-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00012-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00013-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00014-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00015-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00016-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00017-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00018-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00019-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00020-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00021-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00022-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00023-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00024-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00025-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00026-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00027-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results-00028-of-00030
gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/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/1598316390786/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: 9a25ee0023924912b21d30d4fabe8c77 and timestamp: 1598317224.2084987:
apache_beam.testing.load_tests.load_test_metrics_utils: INFO: Metric: runtime
Value: 833
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/1598316390786/results'
apache_beam.io.filesystem: DEBUG: translate_pattern:
'gs://temp-storage-for-end-to-end-tests/py-it-cloud/output/1598316390786/results*'
->
'gs://temp\\-storage\\-for\\-end\\-to\\-end\\-tests/py\\-it\\-cloud/output/1598316390786/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.058439016342163086 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 833.914s
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 56.385 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 15m 6s
5 actionable tasks: 5 executed
Publishing build scan...
https://gradle.com/s/wjpi3hlzuqpqe
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]
