See
<https://ci-beam.apache.org/job/beam_PerformanceTests_Kafka_IO/1538/display/redirect>
Changes:
------------------------------------------
[...truncated 396.69 KB...]
Nov 11, 2020 7:47:54 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Generate records as step s1
Nov 11, 2020 7:47:54 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Measure write time as step s2
Nov 11, 2020 7:47:54 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Write to Kafka/Kafka ProducerRecord/Map as step s3
Nov 11, 2020 7:47:54 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Write to Kafka/KafkaIO.WriteRecords/ParDo(KafkaWriter) as step
s4
Nov 11, 2020 7:47:54 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: Staging pipeline description to
gs://dataflow-staging-us-central1-844138762903/temp/staging/
Nov 11, 2020 7:47:55 PM org.apache.beam.runners.dataflow.util.PackageUtil
tryStagePackage
INFO: Uploading <91353 bytes, hash
8123e36efb60e4749845a556a9fdf66e1c2f2965c960fbafd3d9dd21cf9045e0> to
gs://dataflow-staging-us-central1-844138762903/temp/staging/pipeline-gSPjbvtg5HSYRaVWqf32bhwvKWXJYPuv09ndIc-QReA.pb
Nov 11, 2020 7:47:55 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: Dataflow SDK version: 2.27.0-SNAPSHOT
Nov 11, 2020 7:47:56 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: To access the Dataflow monitoring console, please navigate to
https://console.cloud.google.com/dataflow/jobs/us-central1/2020-11-11_11_47_55-12852911978057580695?project=apache-beam-testing
Nov 11, 2020 7:47:56 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: Submitted job: 2020-11-11_11_47_55-12852911978057580695
Nov 11, 2020 7:47:56 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: To cancel the job using the 'gcloud' tool, run:
> gcloud dataflow jobs --project=apache-beam-testing cancel
--region=us-central1 2020-11-11_11_47_55-12852911978057580695
Nov 11, 2020 7:48:09 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:08.970Z: Worker configuration: n1-standard-1 in
us-central1-f.
Nov 11, 2020 7:48:11 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:09.913Z: Expanding CoGroupByKey operations into
optimizable parts.
Nov 11, 2020 7:48:11 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:09.960Z: Expanding GroupByKey operations into
optimizable parts.
Nov 11, 2020 7:48:11 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:09.998Z: Lifting ValueCombiningMappingFns into
MergeBucketsMappingFns
Nov 11, 2020 7:48:11 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:10.076Z: Fusing adjacent ParDo, Read, Write, and
Flatten operations
Nov 11, 2020 7:48:11 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:10.131Z: Fusing consumer Measure write time into
Generate records
Nov 11, 2020 7:48:11 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:10.179Z: Fusing consumer Write to Kafka/Kafka
ProducerRecord/Map into Measure write time
Nov 11, 2020 7:48:11 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:10.222Z: Fusing consumer Write to
Kafka/KafkaIO.WriteRecords/ParDo(KafkaWriter) into Write to Kafka/Kafka
ProducerRecord/Map
Nov 11, 2020 7:48:11 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:10.757Z: Executing operation Generate
records+Measure write time+Write to Kafka/Kafka ProducerRecord/Map+Write to
Kafka/KafkaIO.WriteRecords/ParDo(KafkaWriter)
Nov 11, 2020 7:48:11 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:10.833Z: Starting 5 ****s in us-central1-f...
Nov 11, 2020 7:48:37 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:36.505Z: 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
Nov 11, 2020 7:48:39 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:37.668Z: Autoscaling: Raised the number of ****s to
3 based on the rate of progress in the currently running stage(s).
Nov 11, 2020 7:48:39 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:37.698Z: Resized **** pool to 3, though goal was 5.
This could be a quota issue.
Nov 11, 2020 7:48:49 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:48:48.036Z: Autoscaling: Raised the number of ****s to
5 based on the rate of progress in the currently running stage(s).
Nov 11, 2020 7:49:01 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:49:01.320Z: Workers have started successfully.
Nov 11, 2020 7:49:01 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:49:01.365Z: Workers have started successfully.
Nov 11, 2020 7:52:33 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:52:32.023Z: Finished operation Generate records+Measure
write time+Write to Kafka/Kafka ProducerRecord/Map+Write to
Kafka/KafkaIO.WriteRecords/ParDo(KafkaWriter)
Nov 11, 2020 7:52:33 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:52:32.229Z: Cleaning up.
Nov 11, 2020 7:52:33 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:52:32.346Z: Stopping **** pool...
Nov 11, 2020 7:53:18 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:17.234Z: Autoscaling: Resized **** pool from 5 to 0.
Nov 11, 2020 7:53:18 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:17.273Z: Worker pool stopped.
Nov 11, 2020 7:53:23 PM
org.apache.beam.runners.dataflow.DataflowPipelineJob logTerminalState
INFO: Job 2020-11-11_11_47_55-12852911978057580695 finished with status
DONE.
Nov 11, 2020 7:53:23 PM
org.apache.beam.sdk.extensions.gcp.options.GcpOptions$GcpTempLocationFactory
tryCreateDefaultBucket
INFO: No tempLocation specified, attempting to use default bucket:
dataflow-staging-us-central1-844138762903
Nov 11, 2020 7:53:23 PM
org.apache.beam.sdk.extensions.gcp.util.RetryHttpRequestInitializer$LoggingHttpBackOffHandler
handleResponse
WARNING: Request failed with code 409, performed 0 retries due to
IOExceptions, performed 0 retries due to unsuccessful status codes, HTTP
framework says request can be retried, (caller responsible for retrying):
https://storage.googleapis.com/storage/v1/b?predefinedAcl=projectPrivate&predefinedDefaultObjectAcl=projectPrivate&project=apache-beam-testing.
Nov 11, 2020 7:53:23 PM
org.apache.beam.runners.dataflow.options.DataflowPipelineOptions$StagingLocationFactory
create
INFO: No stagingLocation provided, falling back to gcpTempLocation
Nov 11, 2020 7:53:24 PM org.apache.beam.runners.dataflow.DataflowRunner
fromOptions
INFO: PipelineOptions.filesToStage was not specified. Defaulting to files
from the classpath: will stage 209 files. Enable logging at DEBUG level to see
which files will be staged.
Nov 11, 2020 7:53:24 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: Executing pipeline on the Dataflow Service, which will have billing
implications related to Google Compute Engine usage and other Google Cloud
Services.
Nov 11, 2020 7:53:27 PM org.apache.beam.runners.dataflow.util.PackageUtil
stageClasspathElements
INFO: Uploading 210 files from PipelineOptions.filesToStage to staging
location to prepare for execution.
Nov 11, 2020 7:53:27 PM
org.apache.beam.runners.dataflow.util.PackageUtil$PackageAttributes
forFileToStage
INFO: Staging custom dataflow-****.jar as
beam-runners-google-cloud-dataflow-java-legacy-****-2.27.0-SNAPSHOT-HhJP-x8AsxSx989IKGIWeN3hwYBR8ZLLAAjlsr2ObU4.jar
Nov 11, 2020 7:53:31 PM org.apache.beam.runners.dataflow.util.PackageUtil
stageClasspathElements
INFO: Staging files complete: 210 files cached, 0 files newly uploaded in 4
seconds
Nov 11, 2020 7:53:31 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Read from Runner V2
Kafka/Read(KafkaUnboundedSource)/DataflowRunner.StreamingUnboundedRead.ReadWithIds
as step s1
Nov 11, 2020 7:53:31 PM org.apache.kafka.common.config.AbstractConfig logAll
INFO: ConsumerConfig values:
auto.commit.interval.ms = 5000
auto.offset.reset = earliest
bootstrap.servers = [34.72.202.92:32400, 34.68.244.7:32401,
34.72.79.65:32402]
check.crcs = true
client.id =
connections.max.idle.ms = 540000
enable.auto.commit = false
exclude.internal.topics = true
fetch.max.bytes = 52428800
fetch.max.wait.ms = 500
fetch.min.bytes = 1
group.id =
heartbeat.interval.ms = 3000
interceptor.classes = null
internal.leave.group.on.close = true
isolation.level = read_uncommitted
key.deserializer = class
org.apache.kafka.common.serialization.ByteArrayDeserializer
max.partition.fetch.bytes = 1048576
max.poll.interval.ms = 300000
max.poll.records = 500
metadata.max.age.ms = 300000
metric.reporters = []
metrics.num.samples = 2
metrics.recording.level = INFO
metrics.sample.window.ms = 30000
partition.assignment.strategy = [class
org.apache.kafka.clients.consumer.RangeAssignor]
receive.buffer.bytes = 524288
reconnect.backoff.max.ms = 1000
reconnect.backoff.ms = 50
request.timeout.ms = 305000
retry.backoff.ms = 100
sasl.jaas.config = null
sasl.kerberos.kinit.cmd = /usr/bin/kinit
sasl.kerberos.min.time.before.relogin = 60000
sasl.kerberos.service.name = null
sasl.kerberos.ticket.renew.jitter = 0.05
sasl.kerberos.ticket.renew.window.factor = 0.8
sasl.mechanism = GSSAPI
security.protocol = PLAINTEXT
send.buffer.bytes = 131072
session.timeout.ms = 10000
ssl.cipher.suites = null
ssl.enabled.protocols = [TLSv1.2, TLSv1.1, TLSv1]
ssl.endpoint.identification.algorithm = null
ssl.key.password = null
ssl.keymanager.algorithm = SunX509
ssl.keystore.location = null
ssl.keystore.password = null
ssl.keystore.type = JKS
ssl.protocol = TLS
ssl.provider = null
ssl.secure.random.implementation = null
ssl.trustmanager.algorithm = PKIX
ssl.truststore.location = null
ssl.truststore.password = null
ssl.truststore.type = JKS
value.deserializer = class
org.apache.kafka.common.serialization.ByteArrayDeserializer
Nov 11, 2020 7:53:32 PM org.apache.kafka.common.utils.AppInfoParser$AppInfo
<init>
INFO: Kafka version : 1.0.0
Nov 11, 2020 7:53:32 PM org.apache.kafka.common.utils.AppInfoParser$AppInfo
<init>
INFO: Kafka commitId : aaa7af6d4a11b29d
Nov 11, 2020 7:53:33 PM org.apache.beam.sdk.io.kafka.KafkaUnboundedSource
split
INFO: Partitions assigned to split 0 (total 1): beam-0
Nov 11, 2020 7:53:33 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Read from Runner V2 Kafka/Read(KafkaUnboundedSource)/StripIds
as step s2
Nov 11, 2020 7:53:33 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Measure read time as step s3
Nov 11, 2020 7:53:33 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Map records to strings/Map as step s4
Nov 11, 2020 7:53:33 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Counting element as step s5
Nov 11, 2020 7:53:33 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: Staging pipeline description to
gs://dataflow-staging-us-central1-844138762903/temp/staging/
Nov 11, 2020 7:53:33 PM org.apache.beam.runners.dataflow.util.PackageUtil
tryStagePackage
INFO: Uploading <90200 bytes, hash
9c6c07d5aacd8d720f1ce302e9061d32a90df566139713076ebae4825db270ce> to
gs://dataflow-staging-us-central1-844138762903/temp/staging/pipeline-nGwH1arNjXIPHOMC6QYdMqkN9WYTlxMHbrrkgl2ycM4.pb
Nov 11, 2020 7:53:33 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: Dataflow SDK version: 2.27.0-SNAPSHOT
Nov 11, 2020 7:53:35 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: To access the Dataflow monitoring console, please navigate to
https://console.cloud.google.com/dataflow/jobs/us-central1/2020-11-11_11_53_34-15602084449816124095?project=apache-beam-testing
Nov 11, 2020 7:53:35 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: Submitted job: 2020-11-11_11_53_34-15602084449816124095
Nov 11, 2020 7:53:35 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: To cancel the job using the 'gcloud' tool, run:
> gcloud dataflow jobs --project=apache-beam-testing cancel
--region=us-central1 2020-11-11_11_53_34-15602084449816124095
Nov 11, 2020 7:53:48 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:47.433Z: Worker configuration: n1-standard-4 in
us-central1-f.
Nov 11, 2020 7:53:49 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:48.089Z: Expanding CoGroupByKey operations into
optimizable parts.
Nov 11, 2020 7:53:49 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:48.101Z: Expanding SplittableProcessKeyed operations
into optimizable parts.
Nov 11, 2020 7:53:49 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:48.103Z: Expanding GroupByKey operations into
streaming Read/Write steps
Nov 11, 2020 7:53:49 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:48.106Z: Lifting ValueCombiningMappingFns into
MergeBucketsMappingFns
Nov 11, 2020 7:53:49 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:48.121Z: Fusing adjacent ParDo, Read, Write, and
Flatten operations
Nov 11, 2020 7:53:49 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:48.125Z: Fusing consumer Read from Runner V2
Kafka/Read(KafkaUnboundedSource)/StripIds into Read from Runner V2
Kafka/Read(KafkaUnboundedSource)/DataflowRunner.StreamingUnboundedRead.ReadWithIds
Nov 11, 2020 7:53:49 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:48.128Z: Fusing consumer Measure read time into Read
from Runner V2 Kafka/Read(KafkaUnboundedSource)/StripIds
Nov 11, 2020 7:53:49 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:48.130Z: Fusing consumer Map records to strings/Map
into Measure read time
Nov 11, 2020 7:53:49 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:48.133Z: Fusing consumer Counting element into Map
records to strings/Map
Nov 11, 2020 7:53:49 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:48.357Z: Starting 5 ****s...
Nov 11, 2020 7:53:52 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:53:50.822Z: Executing operation Read from Runner V2
Kafka/Read(KafkaUnboundedSource)/DataflowRunner.StreamingUnboundedRead.ReadWithIds+Read
from Runner V2 Kafka/Read(KafkaUnboundedSource)/StripIds+Measure read time+Map
records to strings/Map+Counting element
Nov 11, 2020 7:54:03 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:54:02.439Z: 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
Nov 11, 2020 7:54:32 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:54:31.501Z: Worker configuration: n1-standard-4 in
us-central1-f.
Nov 11, 2020 7:54:34 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2020-11-11T19:54:33.911Z: Workers have started successfully.
Nov 11, 2020 8:08:35 PM
org.apache.beam.runners.dataflow.DataflowPipelineJob waitUntilFinish
WARNING: No terminal state was returned within allotted timeout. State
value RUNNING
org.apache.beam.sdk.io.kafka.KafkaIOIT > testKafkaIOWithRunnerV2 FAILED
java.lang.AssertionError: expected:<100000000> but was:<33636982>
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.failNotEquals(Assert.java:835)
at org.junit.Assert.assertEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:633)
at
org.apache.beam.sdk.io.kafka.KafkaIOIT.testKafkaIOWithRunnerV2(KafkaIOIT.java:144)
Gradle Test Executor 10 finished executing tests.
> Task :sdks:java:io:kafka:integrationTest FAILED
2 tests completed, 1 failed
Finished generating test XML results (0.104 secs) into:
<https://ci-beam.apache.org/job/beam_PerformanceTests_Kafka_IO/ws/src/sdks/java/io/kafka/build/test-results/integrationTest>
Generating HTML test report...
Finished generating test html results (0.132 secs) into:
<https://ci-beam.apache.org/job/beam_PerformanceTests_Kafka_IO/ws/src/sdks/java/io/kafka/build/reports/tests/integrationTest>
Watching 1361 directories to track changes
Watching 1367 directories to track changes
Watching 1368 directories to track changes
:sdks:java:io:kafka:integrationTest (Thread[Execution **** for ':' Thread
2,5,main]) completed. Took 42 mins 16.417 secs.
FAILURE: Build failed with an exception.
* What went wrong:
Execution failed for task ':sdks:java:io:kafka:integrationTest'.
> There were failing tests. See the report at:
> file://<https://ci-beam.apache.org/job/beam_PerformanceTests_Kafka_IO/ws/src/sdks/java/io/kafka/build/reports/tests/integrationTest/index.html>
* 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 7.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See
https://docs.gradle.org/6.7/userguide/command_line_interface.html#sec:command_line_warnings
BUILD FAILED in 54m 9s
107 actionable tasks: 106 executed, 1 from cache
Watching 1368 directories to track changes
Publishing build scan...
https://gradle.com/s/uonneiyg2qt2y
Stopped 9 **** daemon(s).
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]
