See
<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/5/display/redirect>
Changes:
------------------------------------------
[...truncated 84.20 KB...]
Found existing installation: packaging 22.0
Uninstalling packaging-22.0:
Successfully uninstalled packaging-22.0
Successfully installed PyJWT-2.6.0 apache-beam-2.45.0.dev0 attrs-22.2.0
azure-core-1.26.1 azure-identity-1.12.0 azure-storage-blob-12.14.1
boto3-1.26.36 botocore-1.29.36 cachetools-4.2.4 certifi-2022.12.7 cffi-1.15.1
charset-normalizer-2.1.1 cloudpickle-2.2.0 crcmod-1.7 cryptography-38.0.4
deprecation-2.1.0 dill-0.3.1.1 docker-6.0.1 docopt-0.6.2 exceptiongroup-1.0.4
execnet-1.9.0 fastavro-1.7.0 fasteners-0.18 freezegun-1.2.2
google-api-core-2.11.0 google-apitools-0.5.31 google-auth-2.15.0
google-auth-httplib2-0.1.0 google-cloud-bigquery-3.4.1
google-cloud-bigquery-storage-2.16.2 google-cloud-bigtable-1.7.3
google-cloud-core-2.3.2 google-cloud-datastore-1.15.5 google-cloud-dlp-3.10.0
google-cloud-language-1.3.2 google-cloud-pubsub-2.13.11
google-cloud-pubsublite-1.6.0 google-cloud-recommendations-ai-0.7.1
google-cloud-spanner-3.26.0 google-cloud-videointelligence-1.16.3
google-cloud-vision-3.2.0 google-crc32c-1.5.0 google-resumable-media-2.4.0
googleapis-common-protos-1.57.0 greenlet-2.0.1 grpc-google-iam-v1-0.12.4
grpcio-status-1.48.2 hdfs-2.7.0 httplib2-0.20.4 hypothesis-6.61.0 idna-3.4
iniconfig-1.1.1 isodate-0.6.1 jmespath-1.0.1 joblib-1.2.0 mock-2.0.0
msal-1.20.0 msal-extensions-1.0.0 msrest-0.7.1 oauth2client-4.1.3
oauthlib-3.2.2 objsize-0.6.1 orjson-3.8.3 overrides-6.5.0 packaging-21.3
pandas-1.3.5 parameterized-0.8.1 pbr-5.11.0 portalocker-2.6.0 proto-plus-1.22.1
psycopg2-binary-2.9.5 pyarrow-9.0.0 pyasn1-0.4.8 pyasn1-modules-0.2.8
pycparser-2.21 pydot-1.4.2 pyhamcrest-1.10.1 pymongo-3.13.0 pymysql-1.0.2
pyparsing-3.0.9 pytest-7.2.0 pytest-forked-1.4.0 pytest-timeout-2.1.0
pytest-xdist-2.5.0 python-dateutil-2.8.2 pytz-2022.7 pyyaml-6.0
regex-2022.10.31 requests-2.28.1 requests-oauthlib-1.3.1 requests_mock-1.10.0
rsa-4.9 s3transfer-0.6.0 scikit-learn-1.0.2 scipy-1.7.3 sortedcontainers-2.4.0
sqlalchemy-1.4.45 sqlparse-0.4.3 tenacity-5.1.5 testcontainers-3.7.1
threadpoolctl-3.1.0 tomli-2.0.1 typing-extensions-4.4.0 urllib3-1.26.13
websocket-client-1.4.2 wrapt-1.14.1 zstandard-0.19.0
> Task :sdks:python:apache_beam:testing:load_tests:run
INFO:apache_beam.utils.subprocess_server:Using pre-built snapshot at
<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/java/io/expansion-service/build/libs/beam-sdks-java-io-expansion-service-2.45.0-SNAPSHOT.jar>
INFO:root:Starting a JAR-based expansion service from JAR
<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/java/io/expansion-service/build/libs/beam-sdks-java-io-expansion-service-2.45.0-SNAPSHOT.jar>
INFO:apache_beam.utils.subprocess_server:Starting service with ['java' '-jar'
'<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/java/io/expansion-service/build/libs/beam-sdks-java-io-expansion-service-2.45.0-SNAPSHOT.jar'>
'48541'
'--filesToStage=<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/java/io/expansion-service/build/libs/beam-sdks-java-io-expansion-service-2.45.0-SNAPSHOT.jar']>
INFO:apache_beam.utils.subprocess_server:Starting expansion service at
localhost:48541
INFO:apache_beam.utils.subprocess_server:Dec 23, 2022 5:38:26 AM
org.apache.beam.sdk.expansion.service.ExpansionService loadRegisteredTransforms
INFO:apache_beam.utils.subprocess_server:INFO: Registering external transforms:
[beam:transform:org.apache.beam:kafka_read_with_metadata:v1,
beam:transform:org.apache.beam:kafka_read_without_metadata:v1,
beam:transform:org.apache.beam:kafka_write:v1,
beam:external:java:generate_sequence:v1]
INFO:apache_beam.utils.subprocess_server:
INFO:apache_beam.utils.subprocess_server:Registered transforms:
INFO:apache_beam.utils.subprocess_server:
beam:transform:org.apache.beam:kafka_read_with_metadata:v1:
org.apache.beam.sdk.expansion.service.ExpansionService$ExternalTransformRegistrarLoader$1@e50a6f6
INFO:apache_beam.utils.subprocess_server:
beam:transform:org.apache.beam:kafka_read_without_metadata:v1:
org.apache.beam.sdk.expansion.service.ExpansionService$ExternalTransformRegistrarLoader$1@14ec4505
INFO:apache_beam.utils.subprocess_server:
beam:transform:org.apache.beam:kafka_write:v1:
org.apache.beam.sdk.expansion.service.ExpansionService$ExternalTransformRegistrarLoader$1@53ca01a2
INFO:apache_beam.utils.subprocess_server:
beam:external:java:generate_sequence:v1:
org.apache.beam.sdk.expansion.service.ExpansionService$ExternalTransformRegistrarLoader$1@358c99f5
INFO:apache_beam.utils.subprocess_server:
INFO:apache_beam.utils.subprocess_server:Registered SchemaTransformProviders:
INFO:apache_beam.utils.subprocess_server:
beam:schematransform:org.apache.beam:kafka_read:v1
INFO:apache_beam.utils.subprocess_server:Dec 23, 2022 5:38:27 AM
org.apache.beam.sdk.expansion.service.ExpansionService expand
INFO:apache_beam.utils.subprocess_server:INFO: Expanding 'WriteToKafka' with
URN 'beam:transform:org.apache.beam:kafka_write:v1'
INFO:apache_beam.utils.subprocess_server:Dec 23, 2022 5:38:28 AM
org.apache.beam.sdk.expansion.service.ExpansionService$ExternalTransformRegistrarLoader
payloadToConfig
INFO:apache_beam.utils.subprocess_server:WARNING: Configuration class
'org.apache.beam.sdk.io.kafka.KafkaIO$Write$External$Configuration' has no
schema registered. Attempting to construct with setter approach.
INFO:apache_beam.utils.subprocess_server:Dec 23, 2022 5:38:28 AM
org.apache.beam.sdk.expansion.service.ExpansionService$ExternalTransformRegistrarLoader
payloadToConfig
INFO:apache_beam.utils.subprocess_server:WARNING: Configuration class
'org.apache.beam.sdk.io.kafka.KafkaIO$Write$External$Configuration' has no
schema registered. Attempting to construct with setter approach.
INFO:apache_beam.runners.dataflow.dataflow_runner:Automatically enabling
Dataflow Runner V2 since the pipeline used cross-language transforms.
INFO:apache_beam.runners.portability.stager:Copying Beam SDK
"<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/python/build/apache-beam.tar.gz";>
to staging location.
INFO:apache_beam.runners.dataflow.dataflow_runner:Pipeline has additional
dependencies to be installed in SDK **** container, consider using the SDK
container image pre-building workflow to avoid repetitive installations. Learn
more on
https://cloud.google.com/dataflow/docs/guides/using-custom-containers#prebuild
INFO:root:Default Python SDK image for environment is
apache/beam_python3.7_sdk:2.45.0.dev
INFO:root:Using provided Python SDK container image:
gcr.io/cloud-dataflow/v1beta3/python37-fnapi:beam-master-20221205
INFO:root:Python SDK container image set to
"gcr.io/cloud-dataflow/v1beta3/python37-fnapi:beam-master-20221205" for Docker
environment
INFO:apache_beam.runners.portability.fn_api_runner.translations:====================
<function pack_combiners at 0x7fd1ec3e40e0> ====================
INFO:apache_beam.runners.portability.fn_api_runner.translations:====================
<function sort_stages at 0x7fd1ec3e48c0> ====================
INFO:apache_beam.runners.dataflow.internal.apiclient:Defaulting to the
temp_location as staging_location: gs://temp-storage-for-perf-tests/
INFO:apache_beam.internal.gcp.auth:Setting socket default timeout to 60 seconds.
INFO:apache_beam.internal.gcp.auth:socket default timeout is 60.0 seconds.
INFO:apache_beam.runners.dataflow.internal.apiclient:Starting GCS upload to
gs://temp-storage-for-perf-tests/beamapp-jenkins-1223053831-108598-q6wj56bx.1671773911.108921/beam-sdks-java-io-expansion-service-2.45.0-SNAPSHOT-M9S8eOAlbHU32ueZanc7urT7Ut8avVN9__JqfoDn1k8.jar...
INFO:apache_beam.runners.dataflow.internal.apiclient:Completed GCS upload to
gs://temp-storage-for-perf-tests/beamapp-jenkins-1223053831-108598-q6wj56bx.1671773911.108921/beam-sdks-java-io-expansion-service-2.45.0-SNAPSHOT-M9S8eOAlbHU32ueZanc7urT7Ut8avVN9__JqfoDn1k8.jar
in 2 seconds.
INFO:apache_beam.runners.dataflow.internal.apiclient:Starting GCS upload to
gs://temp-storage-for-perf-tests/beamapp-jenkins-1223053831-108598-q6wj56bx.1671773911.108921/dataflow_python_sdk.tar...
INFO:apache_beam.runners.dataflow.internal.apiclient:Completed GCS upload to
gs://temp-storage-for-perf-tests/beamapp-jenkins-1223053831-108598-q6wj56bx.1671773911.108921/dataflow_python_sdk.tar
in 0 seconds.
INFO:apache_beam.runners.dataflow.internal.apiclient:Starting GCS upload to
gs://temp-storage-for-perf-tests/beamapp-jenkins-1223053831-108598-q6wj56bx.1671773911.108921/pipeline.pb...
INFO:apache_beam.runners.dataflow.internal.apiclient:Completed GCS upload to
gs://temp-storage-for-perf-tests/beamapp-jenkins-1223053831-108598-q6wj56bx.1671773911.108921/pipeline.pb
in 0 seconds.
INFO:apache_beam.runners.dataflow.internal.apiclient:Create job: <Job
clientRequestId: '20221223053831110116-5312'
createTime: '2022-12-23T05:38:34.766011Z'
currentStateTime: '1970-01-01T00:00:00Z'
id: '2022-12-22_21_38_34-953997303986533386'
location: 'us-central1'
name: 'beamapp-jenkins-1223053831-108598-q6wj56bx'
projectId: 'apache-beam-testing'
stageStates: []
startTime: '2022-12-23T05:38:34.766011Z'
steps: []
tempFiles: []
type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)>
INFO:apache_beam.runners.dataflow.internal.apiclient:Created job with id:
[2022-12-22_21_38_34-953997303986533386]
INFO:apache_beam.runners.dataflow.internal.apiclient:Submitted job:
2022-12-22_21_38_34-953997303986533386
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/2022-12-22_21_38_34-953997303986533386?project=apache-beam-testing
INFO:apache_beam.runners.dataflow.dataflow_runner:Job
2022-12-22_21_38_34-953997303986533386 is in state JOB_STATE_PENDING
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:37.440Z:
JOB_MESSAGE_BASIC: Worker configuration: e2-standard-2 in us-central1-f.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:38.613Z:
JOB_MESSAGE_DETAILED: Expanding SplittableParDo operations into optimizable
parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:38.663Z:
JOB_MESSAGE_DETAILED: Expanding CollectionToSingleton operations into
optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:38.737Z:
JOB_MESSAGE_DETAILED: Expanding CoGroupByKey operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:38.778Z:
JOB_MESSAGE_DEBUG: Combiner lifting skipped for step Avoid
Fusion/ReshufflePerKey/GroupByKey: GroupByKey not followed by a combiner.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:38.825Z:
JOB_MESSAGE_DETAILED: Expanding GroupByKey operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:38.859Z:
JOB_MESSAGE_DEBUG: Annotating graph with Autotuner information.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:38.908Z:
JOB_MESSAGE_DETAILED: Fusing adjacent ParDo, Read, Write, and Flatten operations
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:38.948Z:
JOB_MESSAGE_DETAILED: Fusing consumer Generate records/Map(<lambda at
iobase.py:908>) into Generate records/Impulse
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:38.975Z:
JOB_MESSAGE_DETAILED: Fusing consumer
ref_AppliedPTransform_Generate-records-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_6/PairWithRestriction
into Generate records/Map(<lambda at iobase.py:908>)
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:38.998Z:
JOB_MESSAGE_DETAILED: Fusing consumer
ref_AppliedPTransform_Generate-records-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_6/SplitWithSizing
into
ref_AppliedPTransform_Generate-records-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_6/PairWithRestriction
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.031Z:
JOB_MESSAGE_DETAILED: Fusing consumer Count records into
ref_AppliedPTransform_Generate-records-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_6/ProcessElementAndRestrictionWithSizing
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.064Z:
JOB_MESSAGE_DETAILED: Fusing consumer Avoid Fusion/AddRandomKeys into Count
records
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.097Z:
JOB_MESSAGE_DETAILED: Fusing consumer Avoid
Fusion/ReshufflePerKey/Map(reify_timestamps) into Avoid Fusion/AddRandomKeys
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.131Z:
JOB_MESSAGE_DETAILED: Fusing consumer Avoid
Fusion/ReshufflePerKey/GroupByKey/Reify into Avoid
Fusion/ReshufflePerKey/Map(reify_timestamps)
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.165Z:
JOB_MESSAGE_DETAILED: Fusing consumer Avoid
Fusion/ReshufflePerKey/GroupByKey/Write into Avoid
Fusion/ReshufflePerKey/GroupByKey/Reify
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.199Z:
JOB_MESSAGE_DETAILED: Fusing consumer Avoid
Fusion/ReshufflePerKey/GroupByKey/GroupByWindow into Avoid
Fusion/ReshufflePerKey/GroupByKey/Read
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.232Z:
JOB_MESSAGE_DETAILED: Fusing consumer Avoid
Fusion/ReshufflePerKey/FlatMap(restore_timestamps) into Avoid
Fusion/ReshufflePerKey/GroupByKey/GroupByWindow
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.266Z:
JOB_MESSAGE_DETAILED: Fusing consumer Avoid Fusion/RemoveRandomKeys into Avoid
Fusion/ReshufflePerKey/FlatMap(restore_timestamps)
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.288Z:
JOB_MESSAGE_DETAILED: Fusing consumer Measure time into Avoid
Fusion/RemoveRandomKeys
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.318Z:
JOB_MESSAGE_DETAILED: Fusing consumer WriteToKafka/Kafka
ProducerRecord/Map/ParMultiDo(Anonymous) into Measure time
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.353Z:
JOB_MESSAGE_DETAILED: Fusing consumer
WriteToKafka/KafkaIO.WriteRecords/ParDo(KafkaWriter)/ParMultiDo(KafkaWriter)
into WriteToKafka/Kafka ProducerRecord/Map/ParMultiDo(Anonymous)
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.391Z:
JOB_MESSAGE_DEBUG: Workflow config is missing a default resource spec.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.421Z:
JOB_MESSAGE_DEBUG: Adding StepResource setup and teardown to workflow graph.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.456Z:
JOB_MESSAGE_DEBUG: Adding workflow start and stop steps.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.480Z:
JOB_MESSAGE_DEBUG: Assigning stage ids.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.728Z:
JOB_MESSAGE_DEBUG: Executing wait step start20
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.798Z:
JOB_MESSAGE_BASIC: Executing operation Generate records/Impulse+Generate
records/Map(<lambda at
iobase.py:908>)+ref_AppliedPTransform_Generate-records-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_6/PairWithRestriction+ref_AppliedPTransform_Generate-records-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_6/SplitWithSizing
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.851Z:
JOB_MESSAGE_DEBUG: Starting **** pool setup.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:39.885Z:
JOB_MESSAGE_BASIC: Starting 5 ****s in us-central1-f...
INFO:apache_beam.runners.dataflow.dataflow_runner:Job
2022-12-22_21_38_34-953997303986533386 is in state JOB_STATE_RUNNING
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:38:47.387Z:
JOB_MESSAGE_BASIC: Your project already contains 100 Dataflow-created metric
descriptors, so new user metrics of the form custom.googleapis.com/* will not
be created. However, all user metrics are also available in the metric
dataflow.googleapis.com/job/user_counter. If you rely on the custom metrics,
you can delete old / unused metric descriptors. See
https://developers.google.com/apis-explorer/#p/monitoring/v3/monitoring.projects.metricDescriptors.list
and
https://developers.google.com/apis-explorer/#p/monitoring/v3/monitoring.projects.metricDescriptors.delete
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:39:14.918Z:
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of ****s to 1 based on the
rate of progress in the currently running stage(s).
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:39:14.946Z:
JOB_MESSAGE_DETAILED: Autoscaling: Resized **** pool to 1, though goal was 5.
This could be a quota issue.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:39:24.472Z:
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of ****s to 5 based on the
rate of progress in the currently running stage(s).
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:41:14.743Z:
JOB_MESSAGE_DETAILED: Workers have started successfully.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:47:29.565Z:
JOB_MESSAGE_DETAILED: All ****s have finished the startup processes and began
to receive work requests.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:47:31.305Z:
JOB_MESSAGE_BASIC: Finished operation Generate records/Impulse+Generate
records/Map(<lambda at
iobase.py:908>)+ref_AppliedPTransform_Generate-records-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_6/PairWithRestriction+ref_AppliedPTransform_Generate-records-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_6/SplitWithSizing
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:47:31.397Z:
JOB_MESSAGE_DEBUG: Value
"ref_AppliedPTransform_Generate-records-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_6-split-with-sizing-out3"
materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:47:31.468Z:
JOB_MESSAGE_BASIC: Executing operation Avoid
Fusion/ReshufflePerKey/GroupByKey/Create
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:47:32.912Z:
JOB_MESSAGE_BASIC: Finished operation Avoid
Fusion/ReshufflePerKey/GroupByKey/Create
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:47:33.015Z:
JOB_MESSAGE_DEBUG: Value "Avoid Fusion/ReshufflePerKey/GroupByKey/Session"
materialized.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T05:47:33.109Z:
JOB_MESSAGE_BASIC: Executing operation
ref_AppliedPTransform_Generate-records-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_6/ProcessElementAndRestrictionWithSizing+Count
records+Avoid Fusion/AddRandomKeys+Avoid
Fusion/ReshufflePerKey/Map(reify_timestamps)+Avoid
Fusion/ReshufflePerKey/GroupByKey/Reify+Avoid
Fusion/ReshufflePerKey/GroupByKey/Write
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:00:44.334Z:
JOB_MESSAGE_BASIC: Finished operation
ref_AppliedPTransform_Generate-records-SDFBoundedSourceReader-ParDo-SDFBoundedSourceDoFn-_6/ProcessElementAndRestrictionWithSizing+Count
records+Avoid Fusion/AddRandomKeys+Avoid
Fusion/ReshufflePerKey/Map(reify_timestamps)+Avoid
Fusion/ReshufflePerKey/GroupByKey/Reify+Avoid
Fusion/ReshufflePerKey/GroupByKey/Write
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:00:44.436Z:
JOB_MESSAGE_BASIC: Executing operation Avoid
Fusion/ReshufflePerKey/GroupByKey/Close
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:00:44.667Z:
JOB_MESSAGE_BASIC: Finished operation Avoid
Fusion/ReshufflePerKey/GroupByKey/Close
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:00:44.738Z:
JOB_MESSAGE_BASIC: Executing operation Avoid
Fusion/ReshufflePerKey/GroupByKey/Read+Avoid
Fusion/ReshufflePerKey/GroupByKey/GroupByWindow+Avoid
Fusion/ReshufflePerKey/FlatMap(restore_timestamps)+Avoid
Fusion/RemoveRandomKeys+Measure time+WriteToKafka/Kafka
ProducerRecord/Map/ParMultiDo(Anonymous)+WriteToKafka/KafkaIO.WriteRecords/ParDo(KafkaWriter)/ParMultiDo(KafkaWriter)
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:07:20.345Z:
JOB_MESSAGE_BASIC: Finished operation Avoid
Fusion/ReshufflePerKey/GroupByKey/Read+Avoid
Fusion/ReshufflePerKey/GroupByKey/GroupByWindow+Avoid
Fusion/ReshufflePerKey/FlatMap(restore_timestamps)+Avoid
Fusion/RemoveRandomKeys+Measure time+WriteToKafka/Kafka
ProducerRecord/Map/ParMultiDo(Anonymous)+WriteToKafka/KafkaIO.WriteRecords/ParDo(KafkaWriter)/ParMultiDo(KafkaWriter)
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:07:20.421Z:
JOB_MESSAGE_DEBUG: Executing success step success18
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:07:20.522Z:
JOB_MESSAGE_DETAILED: Cleaning up.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:07:20.596Z:
JOB_MESSAGE_DEBUG: Starting **** pool teardown.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:07:20.620Z:
JOB_MESSAGE_BASIC: Stopping **** pool...
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:09:34.960Z:
JOB_MESSAGE_DETAILED: Autoscaling: Resized **** pool from 5 to 0.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:09:35.010Z:
JOB_MESSAGE_BASIC: Worker pool stopped.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:09:35.048Z:
JOB_MESSAGE_DEBUG: Tearing down pending resources...
INFO:apache_beam.runners.dataflow.dataflow_runner:Job
2022-12-22_21_38_34-953997303986533386 is in state JOB_STATE_DONE
INFO:apache_beam.testing.load_tests.load_test_metrics_utils:Load test results
for test: aaf33f6d361947eaae0e85abd070b4c8 and timestamp: 1671775786.5925324:
INFO:apache_beam.testing.load_tests.load_test_metrics_utils:Metric:
write_count_records_total_messages Value: 100000000
INFO:apache_beam.testing.load_tests.load_test_metrics_utils:Metric:
write_runtime Value: 394
INFO:apache_beam.utils.subprocess_server:Using pre-built snapshot at
<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/java/io/expansion-service/build/libs/beam-sdks-java-io-expansion-service-2.45.0-SNAPSHOT.jar>
INFO:root:Starting a JAR-based expansion service from JAR
<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/java/io/expansion-service/build/libs/beam-sdks-java-io-expansion-service-2.45.0-SNAPSHOT.jar>
INFO:apache_beam.utils.subprocess_server:Starting service with ['java' '-jar'
'<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/java/io/expansion-service/build/libs/beam-sdks-java-io-expansion-service-2.45.0-SNAPSHOT.jar'>
'42067'
'--filesToStage=<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/java/io/expansion-service/build/libs/beam-sdks-java-io-expansion-service-2.45.0-SNAPSHOT.jar']>
INFO:apache_beam.utils.subprocess_server:Starting expansion service at
localhost:42067
INFO:apache_beam.utils.subprocess_server:Dec 23, 2022 6:09:47 AM
org.apache.beam.sdk.expansion.service.ExpansionService loadRegisteredTransforms
INFO:apache_beam.utils.subprocess_server:INFO: Registering external transforms:
[beam:transform:org.apache.beam:kafka_read_with_metadata:v1,
beam:transform:org.apache.beam:kafka_read_without_metadata:v1,
beam:transform:org.apache.beam:kafka_write:v1,
beam:external:java:generate_sequence:v1]
INFO:apache_beam.utils.subprocess_server:
INFO:apache_beam.utils.subprocess_server:Registered transforms:
INFO:apache_beam.utils.subprocess_server:
beam:transform:org.apache.beam:kafka_read_with_metadata:v1:
org.apache.beam.sdk.expansion.service.ExpansionService$ExternalTransformRegistrarLoader$1@e50a6f6
INFO:apache_beam.utils.subprocess_server:
beam:transform:org.apache.beam:kafka_read_without_metadata:v1:
org.apache.beam.sdk.expansion.service.ExpansionService$ExternalTransformRegistrarLoader$1@14ec4505
INFO:apache_beam.utils.subprocess_server:
beam:transform:org.apache.beam:kafka_write:v1:
org.apache.beam.sdk.expansion.service.ExpansionService$ExternalTransformRegistrarLoader$1@53ca01a2
INFO:apache_beam.utils.subprocess_server:
beam:external:java:generate_sequence:v1:
org.apache.beam.sdk.expansion.service.ExpansionService$ExternalTransformRegistrarLoader$1@358c99f5
INFO:apache_beam.utils.subprocess_server:
INFO:apache_beam.utils.subprocess_server:Registered SchemaTransformProviders:
INFO:apache_beam.utils.subprocess_server:
beam:schematransform:org.apache.beam:kafka_read:v1
INFO:apache_beam.utils.subprocess_server:Dec 23, 2022 6:09:49 AM
org.apache.beam.sdk.expansion.service.ExpansionService expand
INFO:apache_beam.utils.subprocess_server:INFO: Expanding 'ReadFromKafka' with
URN 'beam:transform:org.apache.beam:kafka_read_without_metadata:v1'
INFO:apache_beam.utils.subprocess_server:Dec 23, 2022 6:09:49 AM
org.apache.beam.sdk.expansion.service.ExpansionService$ExternalTransformRegistrarLoader
payloadToConfig
INFO:apache_beam.utils.subprocess_server:WARNING: Configuration class
'org.apache.beam.sdk.io.kafka.KafkaIO$Read$External$Configuration' has no
schema registered. Attempting to construct with setter approach.
INFO:apache_beam.utils.subprocess_server:Dec 23, 2022 6:09:50 AM
org.apache.beam.sdk.expansion.service.ExpansionService$ExternalTransformRegistrarLoader
payloadToConfig
INFO:apache_beam.utils.subprocess_server:WARNING: Configuration class
'org.apache.beam.sdk.io.kafka.KafkaIO$Read$External$Configuration' has no
schema registered. Attempting to construct with setter approach.
INFO:apache_beam.runners.portability.stager:Copying Beam SDK
"<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/python/build/apache-beam.tar.gz";>
to staging location.
INFO:apache_beam.runners.dataflow.dataflow_runner:Pipeline has additional
dependencies to be installed in SDK **** container, consider using the SDK
container image pre-building workflow to avoid repetitive installations. Learn
more on
https://cloud.google.com/dataflow/docs/guides/using-custom-containers#prebuild
INFO:root:Default Python SDK image for environment is
apache/beam_python3.7_sdk:2.45.0.dev
INFO:root:Using provided Python SDK container image:
gcr.io/cloud-dataflow/v1beta3/python37-fnapi:beam-master-20221205
INFO:root:Python SDK container image set to
"gcr.io/cloud-dataflow/v1beta3/python37-fnapi:beam-master-20221205" for Docker
environment
INFO:apache_beam.runners.dataflow.internal.apiclient:Defaulting to the
temp_location as staging_location: gs://temp-storage-for-perf-tests/
INFO:apache_beam.runners.dataflow.internal.apiclient:Starting GCS upload to
gs://temp-storage-for-perf-tests/beamapp-jenkins-1223060953-668692-8103kc2l.1671775793.669171/beam-sdks-java-io-expansion-service-2.45.0-SNAPSHOT-M9S8eOAlbHU32ueZanc7urT7Ut8avVN9__JqfoDn1k8.jar...
INFO:apache_beam.runners.dataflow.internal.apiclient:Completed GCS upload to
gs://temp-storage-for-perf-tests/beamapp-jenkins-1223060953-668692-8103kc2l.1671775793.669171/beam-sdks-java-io-expansion-service-2.45.0-SNAPSHOT-M9S8eOAlbHU32ueZanc7urT7Ut8avVN9__JqfoDn1k8.jar
in 3 seconds.
INFO:apache_beam.runners.dataflow.internal.apiclient:Starting GCS upload to
gs://temp-storage-for-perf-tests/beamapp-jenkins-1223060953-668692-8103kc2l.1671775793.669171/dataflow_python_sdk.tar...
INFO:apache_beam.runners.dataflow.internal.apiclient:Completed GCS upload to
gs://temp-storage-for-perf-tests/beamapp-jenkins-1223060953-668692-8103kc2l.1671775793.669171/dataflow_python_sdk.tar
in 0 seconds.
INFO:apache_beam.runners.dataflow.internal.apiclient:Starting GCS upload to
gs://temp-storage-for-perf-tests/beamapp-jenkins-1223060953-668692-8103kc2l.1671775793.669171/pipeline.pb...
INFO:apache_beam.runners.dataflow.internal.apiclient:Completed GCS upload to
gs://temp-storage-for-perf-tests/beamapp-jenkins-1223060953-668692-8103kc2l.1671775793.669171/pipeline.pb
in 0 seconds.
INFO:apache_beam.runners.dataflow.internal.apiclient:Create job: <Job
clientRequestId: '20221223060953670291-5291'
createTime: '2022-12-23T06:09:57.853873Z'
currentStateTime: '1970-01-01T00:00:00Z'
id: '2022-12-22_22_09_57-5198151713108329746'
location: 'us-central1'
name: 'beamapp-jenkins-1223060953-668692-8103kc2l'
projectId: 'apache-beam-testing'
stageStates: []
startTime: '2022-12-23T06:09:57.853873Z'
steps: []
tempFiles: []
type: TypeValueValuesEnum(JOB_TYPE_STREAMING, 2)>
INFO:apache_beam.runners.dataflow.internal.apiclient:Created job with id:
[2022-12-22_22_09_57-5198151713108329746]
INFO:apache_beam.runners.dataflow.internal.apiclient:Submitted job:
2022-12-22_22_09_57-5198151713108329746
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/2022-12-22_22_09_57-5198151713108329746?project=apache-beam-testing
INFO:apache_beam.runners.dataflow.dataflow_runner:Job
2022-12-22_22_09_57-5198151713108329746 is in state JOB_STATE_PENDING
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:05.927Z:
JOB_MESSAGE_BASIC: Worker configuration: e2-standard-2 in us-central1-b.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.128Z:
JOB_MESSAGE_DETAILED: Expanding SplittableParDo operations into optimizable
parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.147Z:
JOB_MESSAGE_DETAILED: Expanding CollectionToSingleton operations into
optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.198Z:
JOB_MESSAGE_DETAILED: Expanding CoGroupByKey operations into optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.231Z:
JOB_MESSAGE_DETAILED: Expanding SplittableProcessKeyed operations into
optimizable parts.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.268Z:
JOB_MESSAGE_DETAILED: Expanding GroupByKey operations into streaming Read/Write
steps
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.301Z:
JOB_MESSAGE_DETAILED: Lifting ValueCombiningMappingFns into
MergeBucketsMappingFns
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.336Z:
JOB_MESSAGE_DEBUG: Annotating graph with Autotuner information.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.367Z:
JOB_MESSAGE_DETAILED: Fusing adjacent ParDo, Read, Write, and Flatten operations
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.417Z:
JOB_MESSAGE_DETAILED: Fusing consumer
ReadFromKafka/KafkaIO.Read/KafkaIO.Read.ReadFromKafkaViaSDF/ParDo(GenerateKafkaSourceDescriptor)/ParMultiDo(GenerateKafkaSourceDescriptor)
into ReadFromKafka/KafkaIO.Read/KafkaIO.Read.ReadFromKafkaViaSDF/Impulse
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.450Z:
JOB_MESSAGE_DETAILED: Fusing consumer
external_2ReadFromKafka-KafkaIO-Read-KafkaIO-Read-ReadFromKafkaViaSDF-KafkaIO-ReadSourceDescriptors-ParDo-Unbo/PairWithRestriction
into
ReadFromKafka/KafkaIO.Read/KafkaIO.Read.ReadFromKafkaViaSDF/ParDo(GenerateKafkaSourceDescriptor)/ParMultiDo(GenerateKafkaSourceDescriptor)
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.485Z:
JOB_MESSAGE_DETAILED: Fusing consumer
external_2ReadFromKafka-KafkaIO-Read-KafkaIO-Read-ReadFromKafkaViaSDF-KafkaIO-ReadSourceDescriptors-ParDo-Unbo/SplitWithSizing
into
external_2ReadFromKafka-KafkaIO-Read-KafkaIO-Read-ReadFromKafkaViaSDF-KafkaIO-ReadSourceDescriptors-ParDo-Unbo/PairWithRestriction
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.520Z:
JOB_MESSAGE_DETAILED: Fusing consumer
ReadFromKafka/KafkaIO.Read/KafkaIO.Read.ReadFromKafkaViaSDF/KafkaIO.ReadSourceDescriptors/MapElements/Map/ParMultiDo(Anonymous)
into
external_2ReadFromKafka-KafkaIO-Read-KafkaIO-Read-ReadFromKafkaViaSDF-KafkaIO-ReadSourceDescriptors-ParDo-Unbo/ProcessElementAndRestrictionWithSizing
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.541Z:
JOB_MESSAGE_DETAILED: Fusing consumer ReadFromKafka/Remove Kafka
Metadata/ParMultiDo(Anonymous) into
ReadFromKafka/KafkaIO.Read/KafkaIO.Read.ReadFromKafkaViaSDF/KafkaIO.ReadSourceDescriptors/MapElements/Map/ParMultiDo(Anonymous)
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.573Z:
JOB_MESSAGE_DETAILED: Fusing consumer Count records into ReadFromKafka/Remove
Kafka Metadata/ParMultiDo(Anonymous)
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.599Z:
JOB_MESSAGE_DETAILED: Fusing consumer Measure time into Count records
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.693Z:
JOB_MESSAGE_BASIC: Running job using Streaming Engine
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.724Z:
JOB_MESSAGE_DEBUG: Workflow config is missing a default resource spec.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.750Z:
JOB_MESSAGE_DEBUG: Adding StepResource setup and teardown to workflow graph.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.778Z:
JOB_MESSAGE_DEBUG: Adding workflow start and stop steps.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:08.803Z:
JOB_MESSAGE_DEBUG: Assigning stage ids.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:09.860Z:
JOB_MESSAGE_DEBUG: Starting **** pool setup.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:09.894Z:
JOB_MESSAGE_DEBUG: Starting **** pool setup.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:09.923Z:
JOB_MESSAGE_BASIC: Starting 5 ****s in us-central1-b...
INFO:apache_beam.runners.dataflow.dataflow_runner:Job
2022-12-22_22_09_57-5198151713108329746 is in state JOB_STATE_RUNNING
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:32.691Z:
JOB_MESSAGE_BASIC: Your project already contains 100 Dataflow-created metric
descriptors, so new user metrics of the form custom.googleapis.com/* will not
be created. However, all user metrics are also available in the metric
dataflow.googleapis.com/job/user_counter. If you rely on the custom metrics,
you can delete old / unused metric descriptors. See
https://developers.google.com/apis-explorer/#p/monitoring/v3/monitoring.projects.metricDescriptors.list
and
https://developers.google.com/apis-explorer/#p/monitoring/v3/monitoring.projects.metricDescriptors.delete
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:10:54.850Z:
JOB_MESSAGE_DETAILED: Autoscaling: Raised the number of ****s to 5 so that the
pipeline can catch up with its backlog and keep up with its input rate.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:12:49.182Z:
JOB_MESSAGE_DETAILED: Workers have started successfully.
INFO:apache_beam.runners.dataflow.dataflow_runner:2022-12-23T06:12:49.279Z:
JOB_MESSAGE_DETAILED: All ****s have finished the startup processes and began
to receive work requests.
WARNING:apache_beam.runners.dataflow.dataflow_runner:Timing out on waiting for
job 2022-12-22_22_09_57-5198151713108329746 after 1501 seconds
INFO:apache_beam.testing.load_tests.load_test_metrics_utils:Load test results
for test: 733a71f53b9c4e0596fa5f2a16680ccc and timestamp: 1671777304.3577065:
INFO:apache_beam.testing.load_tests.load_test_metrics_utils:Metric:
read_count_records_total_messages Value: 35656006
INFO:apache_beam.testing.load_tests.load_test_metrics_utils:Metric:
read_runtime Value: 892
Traceback (most recent call last):
File "/usr/lib/python3.7/runpy.py", line 193, in _run_module_as_main
"__main__", mod_spec)
File "/usr/lib/python3.7/runpy.py", line 85, in _run_code
exec(code, run_globals)
File
"<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/python/apache_beam/io/external/xlang_kafkaio_perf_test.py";,>
line 143, in <module>
getattr(sys.modules[__name__], test_options.test_class)().run()
File
"<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/python/apache_beam/io/external/xlang_kafkaio_perf_test.py";,>
line 65, in run
read_test.run()
File
"<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/python/apache_beam/testing/load_tests/load_test.py";,>
line 156, in run
self.cleanup()
File
"<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/python/apache_beam/io/external/xlang_kafkaio_perf_test.py";,>
line 125, in cleanup
assert total_messages == self.input_options['num_records']
AssertionError
> Task :sdks:python:apache_beam:testing:load_tests:run FAILED
FAILURE: Build failed with an exception.
* Where:
Build file
'<https://ci-beam.apache.org/job/beam_PerformanceTests_xlang_KafkaIO_Python/ws/src/sdks/python/apache_beam/testing/load_tests/build.gradle'>
line: 63
* What went wrong:
Execution failed for task ':sdks:python:apache_beam:testing:load_tests:run'.
> error occurred
* Try:
> Run with --stacktrace option to get the stack trace.
> Run with --info or --debug option to get more log output.
* Get more help at https://help.gradle.org
Deprecated Gradle features were used in this build, making it incompatible with
Gradle 8.0.
You can use '--warning-mode all' to show the individual deprecation warnings
and determine if they come from your own scripts or plugins.
See
https://docs.gradle.org/7.5.1/userguide/command_line_interface.html#sec:command_line_warnings
BUILD FAILED in 58m 42s
15 actionable tasks: 5 executed, 10 up-to-date
Publishing build scan...
https://gradle.com/s/3c5vlzph2wgpg
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]
