See
<https://ci-beam.apache.org/job/beam_PerformanceTests_Kafka_IO/2506/display/redirect>
Changes:
------------------------------------------
[...truncated 1.36 MB...]
INFO: No tempLocation specified, attempting to use default bucket:
dataflow-staging-us-central1-844138762903
Jul 08, 2021 9:15:48 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.
Jul 08, 2021 9:15:48 PM
org.apache.beam.runners.dataflow.options.DataflowPipelineOptions$StagingLocationFactory
create
INFO: No stagingLocation provided, falling back to gcpTempLocation
Jul 08, 2021 9:15:48 PM org.apache.beam.runners.dataflow.DataflowRunner
fromOptions
INFO: PipelineOptions.filesToStage was not specified. Defaulting to files
from the classpath: will stage 229 files. Enable logging at DEBUG level to see
which files will be staged.
Jul 08, 2021 9:15:48 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.
Jul 08, 2021 9:15:50 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: Staging portable pipeline proto to
gs://dataflow-staging-us-central1-844138762903/temp/staging/
Jul 08, 2021 9:15:50 PM org.apache.beam.runners.dataflow.util.PackageUtil
tryStagePackage
INFO: Uploading <113288 bytes, hash
b15d9584abd501ba2de15d8dfd4f757630bc765bd5d21516a350c6dcbaa5acba> to
gs://dataflow-staging-us-central1-844138762903/temp/staging/pipeline-sV2VhKvVAbot4V2N_U91djC8dlvV0hUWo1DG3LqlrLo.pb
Jul 08, 2021 9:15:52 PM org.apache.beam.runners.dataflow.util.PackageUtil
stageClasspathElements
INFO: Uploading 229 files from PipelineOptions.filesToStage to staging
location to prepare for execution.
Jul 08, 2021 9:15:52 PM org.apache.beam.runners.dataflow.util.PackageUtil
stageClasspathElements
INFO: Staging files complete: 229 files cached, 0 files newly uploaded in 0
seconds
Jul 08, 2021 9:15:52 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Read from unbounded
Kafka/KafkaIO.Read.ReadFromKafkaViaSDF/Read(KafkaUnboundedSource)/DataflowRunner.StreamingUnboundedRead.ReadWithIds
as step s1
Jul 08, 2021 9:15:52 PM org.apache.kafka.common.config.AbstractConfig logAll
INFO: ConsumerConfig values:
allow.auto.create.topics = true
auto.commit.interval.ms = 5000
auto.offset.reset = earliest
bootstrap.servers = [34.132.89.89:32400, 34.135.155.16:32401,
35.188.54.163:32402]
check.crcs = true
client.dns.lookup = default
client.id =
client.rack =
connections.max.idle.ms = 540000
default.api.timeout.ms = 60000
enable.auto.commit = false
exclude.internal.topics = true
fetch.max.bytes = 52428800
fetch.max.wait.ms = 500
fetch.min.bytes = 1
group.id = null
group.instance.id = null
heartbeat.interval.ms = 3000
interceptor.classes = []
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 = 30000
retry.backoff.ms = 100
sasl.client.callback.handler.class = null
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.login.callback.handler.class = null
sasl.login.class = null
sasl.login.refresh.buffer.seconds = 300
sasl.login.refresh.min.period.seconds = 60
sasl.login.refresh.window.factor = 0.8
sasl.login.refresh.window.jitter = 0.05
sasl.mechanism = GSSAPI
security.protocol = PLAINTEXT
security.providers = null
send.buffer.bytes = 131072
session.timeout.ms = 10000
ssl.cipher.suites = null
ssl.enabled.protocols = [TLSv1.2, TLSv1.1, TLSv1]
ssl.endpoint.identification.algorithm = https
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
Jul 08, 2021 9:15:52 PM org.apache.kafka.common.utils.AppInfoParser$AppInfo
<init>
INFO: Kafka version: 2.4.1
Jul 08, 2021 9:15:52 PM org.apache.kafka.common.utils.AppInfoParser$AppInfo
<init>
INFO: Kafka commitId: c57222ae8cd7866b
Jul 08, 2021 9:15:52 PM org.apache.kafka.common.utils.AppInfoParser$AppInfo
<init>
INFO: Kafka startTimeMs: 1625778952974
Jul 08, 2021 9:15:53 PM org.apache.kafka.clients.Metadata update
INFO: [Consumer clientId=consumer-1, groupId=null] Cluster ID:
w2MZwU2JTGidnv3K_oldsA
Jul 08, 2021 9:15:53 PM org.apache.beam.sdk.io.kafka.KafkaUnboundedSource
split
INFO: Partitions assigned to split 0 (total 1): beam-sdf-0
Jul 08, 2021 9:15:53 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Read from unbounded
Kafka/KafkaIO.Read.ReadFromKafkaViaSDF/Read(KafkaUnboundedSource)/StripIds as
step s2
Jul 08, 2021 9:15:53 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Measure read time as step s3
Jul 08, 2021 9:15:53 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Map records to strings/Map as step s4
Jul 08, 2021 9:15:53 PM
org.apache.beam.runners.dataflow.DataflowPipelineTranslator$Translator addStep
INFO: Adding Counting element as step s5
Jul 08, 2021 9:15:53 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: Dataflow SDK version: 2.32.0-SNAPSHOT
Jul 08, 2021 9:15:54 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/2021-07-08_14_15_53-8726938045236510297?project=apache-beam-testing
Jul 08, 2021 9:15:54 PM org.apache.beam.runners.dataflow.DataflowRunner run
INFO: Submitted job: 2021-07-08_14_15_53-8726938045236510297
Jul 08, 2021 9:15:54 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 2021-07-08_14_15_53-8726938045236510297
Jul 08, 2021 9:16:03 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
WARNING: 2021-07-08T21:16:00.662Z: The workflow name is not a valid Cloud
Label. Labels applied to Cloud resources (such as GCE Instances) for monitoring
will be labeled with this modified job name:
kafkaioit0testkafkaioreadsandwritescorrectlyinstreaming-je-e6qt. For the best
monitoring experience, please name your job with a valid Cloud Label. For
details, see:
https://cloud.google.com/compute/docs/labeling-resources#restrictions
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:12.332Z: Worker configuration: n1-standard-2 in
us-central1-f.
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:12.955Z: Expanding SplittableParDo operations into
optimizable parts.
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.008Z: Expanding CollectionToSingleton operations
into optimizable parts.
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.119Z: Expanding CoGroupByKey operations into
optimizable parts.
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.207Z: Expanding SplittableProcessKeyed operations
into optimizable parts.
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.288Z: Expanding GroupByKey operations into
streaming Read/Write steps
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.319Z: Lifting ValueCombiningMappingFns into
MergeBucketsMappingFns
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.425Z: Fusing adjacent ParDo, Read, Write, and
Flatten operations
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.490Z: Fusing consumer Read from unbounded
Kafka/KafkaIO.Read.ReadFromKafkaViaSDF/ParDo(GenerateKafkaSourceDescriptor)/ParMultiDo(GenerateKafkaSourceDescriptor)
into Read from unbounded Kafka/KafkaIO.Read.ReadFromKafkaViaSDF/Impulse
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.538Z: Fusing consumer
Read-from-unbounded-Kafka-KafkaIO-Read-ReadFromKafkaViaSDF-KafkaIO-ReadSourceDescriptors-ParDo-ReadF/PairWithRestriction
into Read from unbounded
Kafka/KafkaIO.Read.ReadFromKafkaViaSDF/ParDo(GenerateKafkaSourceDescriptor)/ParMultiDo(GenerateKafkaSourceDescriptor)
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.589Z: Fusing consumer
Read-from-unbounded-Kafka-KafkaIO-Read-ReadFromKafkaViaSDF-KafkaIO-ReadSourceDescriptors-ParDo-ReadF/SplitWithSizing
into
Read-from-unbounded-Kafka-KafkaIO-Read-ReadFromKafkaViaSDF-KafkaIO-ReadSourceDescriptors-ParDo-ReadF/PairWithRestriction
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.628Z: Fusing consumer Read from unbounded
Kafka/KafkaIO.Read.ReadFromKafkaViaSDF/KafkaIO.ReadSourceDescriptors/MapElements/Map/ParMultiDo(Anonymous)
into
Read-from-unbounded-Kafka-KafkaIO-Read-ReadFromKafkaViaSDF-KafkaIO-ReadSourceDescriptors-ParDo-ReadF/ProcessElementAndRestrictionWithSizing
Jul 08, 2021 9:16:13 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.677Z: Fusing consumer Measure read
time/ParMultiDo(TimeMonitor) into Read from unbounded
Kafka/KafkaIO.Read.ReadFromKafkaViaSDF/KafkaIO.ReadSourceDescriptors/MapElements/Map/ParMultiDo(Anonymous)
Jul 08, 2021 9:16:16 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.750Z: Fusing consumer Map records to
strings/Map/ParMultiDo(Anonymous) into Measure read time/ParMultiDo(TimeMonitor)
Jul 08, 2021 9:16:16 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:13.809Z: Fusing consumer Counting
element/ParMultiDo(Counting) into Map records to
strings/Map/ParMultiDo(Anonymous)
Jul 08, 2021 9:16:16 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:14.421Z: Starting 5 ****s in us-central1-f...
Jul 08, 2021 9:16:18 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:16:16.825Z: 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
Jul 08, 2021 9:17:46 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:17:46.131Z: Workers have started successfully.
Jul 08, 2021 9:17:49 PM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2021-07-08T21:17:46.292Z: Workers have started successfully.
Jul 08, 2021 9:30:54 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 >
testKafkaIOReadsAndWritesCorrectlyInStreaming STANDARD_OUT
Load test results for test (ID): 47f2b6f5-d162-4c30-8998-da27fa676bc4 and
timestamp: 2021-07-08T21:11:54.182000000Z:
Metric: Value:
read_time 5.145
write_time 13.421
run_time 18.566
Gradle Test Executor 5 finished executing tests.
> Task :sdks:java:io:kafka:integrationTest
Finished generating test XML results (0.002 secs) into:
/home/jenkins/jenkins-slave/workspace/beam_PerformanceTests_Kafka_IO/src/sdks/java/io/kafka/build/test-results/integrationTest
Generating HTML test report...
Finished generating test html results (0.003 secs) into:
/home/jenkins/jenkins-slave/workspace/beam_PerformanceTests_Kafka_IO/src/sdks/java/io/kafka/build/reports/tests/integrationTest
Stored cache entry for task ':sdks:java:io:kafka:integrationTest' with cache
key 1b2a78f482b3e6ce2540da76b78768a1
:sdks:java:io:kafka:integrationTest (Thread[Execution **** for ':' Thread
7,5,main]) completed. Took 19 mins 2.397 secs.
:runners:google-cloud-dataflow-java:cleanUpDockerImages (Thread[Execution ****
for ':' Thread 7,5,main]) started.
> Task :runners:google-cloud-dataflow-java:cleanUpDockerImages
Custom actions are attached to task
':runners:google-cloud-dataflow-java:cleanUpDockerImages'.
Caching disabled for task
':runners:google-cloud-dataflow-java:cleanUpDockerImages' because:
Caching has not been enabled for the task
Task ':runners:google-cloud-dataflow-java:cleanUpDockerImages' is not
up-to-date because:
Task has not declared any outputs despite executing actions.
Starting process 'command 'docker''. Working directory:
/home/jenkins/jenkins-slave/workspace/beam_PerformanceTests_Kafka_IO/src/runners/google-cloud-dataflow-java
Command: docker rmi --force
us.gcr.io/apache-beam-testing/java-postcommit-it/java:20210708211118
Successfully started process 'command 'docker''
Untagged: us.gcr.io/apache-beam-testing/java-postcommit-it/java:20210708211118
Untagged:
us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:1be6f1ea40f6216a9aeed4c6d77ffa823409e41b827f84a618684f0964e7f066
Starting process 'command 'gcloud''. Working directory:
/home/jenkins/jenkins-slave/workspace/beam_PerformanceTests_Kafka_IO/src/runners/google-cloud-dataflow-java
Command: gcloud --quiet container images delete --force-delete-tags
us.gcr.io/apache-beam-testing/java-postcommit-it/java:20210708211118
Successfully started process 'command 'gcloud''
Digests:
-
us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:1be6f1ea40f6216a9aeed4c6d77ffa823409e41b827f84a618684f0964e7f066
Associated tags:
- 20210708211118
Tags:
- us.gcr.io/apache-beam-testing/java-postcommit-it/java:20210708211118
Deleted [us.gcr.io/apache-beam-testing/java-postcommit-it/java:20210708211118].
Deleted
[us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:1be6f1ea40f6216a9aeed4c6d77ffa823409e41b827f84a618684f0964e7f066].
:runners:google-cloud-dataflow-java:cleanUpDockerImages (Thread[Execution ****
for ':' Thread 7,5,main]) completed. Took 2.312 secs.
:sdks:java:io:kafka:cleanUp (Thread[Execution **** for ':' Thread 7,5,main])
started.
> Task :sdks:java:io:kafka:cleanUp
Skipping task ':sdks:java:io:kafka:cleanUp' as it has no actions.
:sdks:java:io:kafka:cleanUp (Thread[Execution **** for ':' Thread 7,5,main])
completed. Took 0.0 secs.
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.8.3/userguide/command_line_interface.html#sec:command_line_warnings
BUILD SUCCESSFUL in 19m 41s
136 actionable tasks: 14 executed, 122 up-to-date
Publishing build scan...
https://gradle.com/s/k5qricolcltqq
FATAL: command execution failed
hudson.remoting.ChannelClosedException: Channel
"hudson.remoting.Channel@797718ed:apache-beam-jenkins-8": Remote call on
apache-beam-jenkins-8 failed. The channel is closing down or has closed down
at hudson.remoting.Channel.call(Channel.java:994)
at
hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:286)
at com.sun.proxy.$Proxy132.isAlive(Unknown Source)
at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1211)
at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1203)
at hudson.Launcher$ProcStarter.join(Launcher.java:523)
at hudson.plugins.gradle.Gradle.perform(Gradle.java:317)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:804)
at hudson.model.Build$BuildExecution.build(Build.java:197)
at hudson.model.Build$BuildExecution.doRun(Build.java:163)
at
hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:514)
at hudson.model.Run.execute(Run.java:1907)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused by: hudson.remoting.Channel$OrderlyShutdown: Command Close created at
at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1322)
at hudson.remoting.Channel$1.handle(Channel.java:607)
at
hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:85)
Caused by: Command Close created at
at hudson.remoting.Command.<init>(Command.java:70)
at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1315)
at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1313)
at hudson.remoting.Channel.close(Channel.java:1488)
at hudson.remoting.Channel.close(Channel.java:1455)
at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1321)
... 2 more
Build step 'Invoke Gradle script' changed build result to FAILURE
Build step 'Invoke Gradle script' marked build as failure
ERROR: apache-beam-jenkins-8 is offline; cannot locate jdk_1.8_latest
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
