See
<https://builds.apache.org/job/beam_PostCommit_Python_Verify/4962/display/redirect>
------------------------------------------
[...truncated 1.14 MB...]
"component_encodings": [
{
"@type": "kind:windowed_value",
"component_encodings": [
{
"@type":
"FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/",
"component_encodings": [
{
"@type":
"FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/",
"component_encodings": []
},
{
"@type":
"FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/",
"component_encodings": []
}
],
"is_pair_like": true
},
{
"@type": "kind:global_window"
}
],
"is_wrapper": true
}
]
},
"output_name": "out",
"user_name":
"write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0).output"
}
],
"parallel_input": {
"@type": "OutputReference",
"output_name": "out",
"step_name": "s17"
},
"user_name":
"write/Write/WriteImpl/FinalizeWrite/_UnpickledSideInput(PreFinalize.out.0)"
}
},
{
"kind": "ParallelDo",
"name": "s21",
"properties": {
"display_data": [
{
"key": "fn",
"label": "Transform Function",
"namespace": "apache_beam.transforms.core.CallableWrapperDoFn",
"type": "STRING",
"value": "_finalize_write"
},
{
"key": "fn",
"label": "Transform Function",
"namespace": "apache_beam.transforms.core.ParDo",
"shortValue": "CallableWrapperDoFn",
"type": "STRING",
"value": "apache_beam.transforms.core.CallableWrapperDoFn"
}
],
"non_parallel_inputs": {
"side0": {
"@type": "OutputReference",
"output_name": "out",
"step_name": "SideInput-s18"
},
"side1": {
"@type": "OutputReference",
"output_name": "out",
"step_name": "SideInput-s19"
},
"side2": {
"@type": "OutputReference",
"output_name": "out",
"step_name": "SideInput-s20"
}
},
"output_info": [
{
"encoding": {
"@type": "kind:windowed_value",
"component_encodings": [
{
"@type":
"FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/",
"component_encodings": [
{
"@type":
"FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/",
"component_encodings": []
},
{
"@type":
"FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/",
"component_encodings": []
}
],
"is_pair_like": true
},
{
"@type": "kind:global_window"
}
],
"is_wrapper": true
},
"output_name": "out",
"user_name": "write/Write/WriteImpl/FinalizeWrite.out"
}
],
"parallel_input": {
"@type": "OutputReference",
"output_name": "out",
"step_name": "s7"
},
"serialized_fn": "<string of 2760 bytes>",
"user_name": "write/Write/WriteImpl/FinalizeWrite/FinalizeWrite"
}
}
],
"type": "JOB_TYPE_BATCH"
}
root: INFO: Create job: <Job
createTime: u'2018-05-14T19:06:40.514657Z'
currentStateTime: u'1970-01-01T00:00:00Z'
id: u'2018-05-14_12_06_39-1090332721983279184'
location: u'us-central1'
name: u'beamapp-jenkins-0514190638-291695'
projectId: u'apache-beam-testing'
stageStates: []
steps: []
tempFiles: []
type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)>
root: INFO: Created job with id: [2018-05-14_12_06_39-1090332721983279184]
root: INFO: To access the Dataflow monitoring console, please navigate to
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-05-14_12_06_39-1090332721983279184?project=apache-beam-testing
root: INFO: Job 2018-05-14_12_06_39-1090332721983279184 is in state
JOB_STATE_RUNNING
root: INFO: 2018-05-14T19:06:39.828Z: JOB_MESSAGE_WARNING: Job
2018-05-14_12_06_39-1090332721983279184 might autoscale up to 1000 workers.
root: INFO: 2018-05-14T19:06:39.858Z: JOB_MESSAGE_DETAILED: Autoscaling is
enabled for job 2018-05-14_12_06_39-1090332721983279184. The number of workers
will be between 1 and 1000.
root: INFO: 2018-05-14T19:06:39.893Z: JOB_MESSAGE_DETAILED: Autoscaling was
automatically enabled for job 2018-05-14_12_06_39-1090332721983279184.
root: INFO: 2018-05-14T19:06:43.316Z: JOB_MESSAGE_DETAILED: Checking required
Cloud APIs are enabled.
root: INFO: 2018-05-14T19:06:43.643Z: JOB_MESSAGE_DETAILED: Checking
permissions granted to controller Service Account.
root: INFO: 2018-05-14T19:06:44.725Z: JOB_MESSAGE_BASIC: Worker configuration:
n1-standard-1 in us-central1-b.
root: INFO: 2018-05-14T19:06:45.215Z: JOB_MESSAGE_DETAILED: Expanding
CoGroupByKey operations into optimizable parts.
root: INFO: 2018-05-14T19:06:45.261Z: JOB_MESSAGE_DEBUG: Combiner lifting
skipped for step write/Write/WriteImpl/GroupByKey: GroupByKey not followed by a
combiner.
root: INFO: 2018-05-14T19:06:45.306Z: JOB_MESSAGE_DEBUG: Combiner lifting
skipped for step group: GroupByKey not followed by a combiner.
root: INFO: 2018-05-14T19:06:45.344Z: JOB_MESSAGE_DETAILED: Expanding
GroupByKey operations into optimizable parts.
root: INFO: 2018-05-14T19:06:45.390Z: JOB_MESSAGE_DETAILED: Lifting
ValueCombiningMappingFns into MergeBucketsMappingFns
root: INFO: 2018-05-14T19:06:45.514Z: JOB_MESSAGE_DEBUG: Annotating graph with
Autotuner information.
root: INFO: 2018-05-14T19:06:45.572Z: JOB_MESSAGE_DETAILED: Fusing adjacent
ParDo, Read, Write, and Flatten operations
root: INFO: 2018-05-14T19:06:45.610Z: JOB_MESSAGE_DETAILED: Fusing consumer
group/Write into group/Reify
root: INFO: 2018-05-14T19:06:45.654Z: JOB_MESSAGE_DETAILED: Fusing consumer
group/GroupByWindow into group/Read
root: INFO: 2018-05-14T19:06:45.691Z: JOB_MESSAGE_DETAILED: Fusing consumer
write/Write/WriteImpl/GroupByKey/GroupByWindow into
write/Write/WriteImpl/GroupByKey/Read
root: INFO: 2018-05-14T19:06:45.737Z: JOB_MESSAGE_DETAILED: Fusing consumer
write/Write/WriteImpl/GroupByKey/Write into
write/Write/WriteImpl/GroupByKey/Reify
root: INFO: 2018-05-14T19:06:45.783Z: JOB_MESSAGE_DETAILED: Fusing consumer
write/Write/WriteImpl/Extract into
write/Write/WriteImpl/GroupByKey/GroupByWindow
root: INFO: 2018-05-14T19:06:45.828Z: JOB_MESSAGE_DETAILED: Fusing consumer
write/Write/WriteImpl/WindowInto(WindowIntoFn) into write/Write/WriteImpl/Pair
root: INFO: 2018-05-14T19:06:45.881Z: JOB_MESSAGE_DETAILED: Fusing consumer
write/Write/WriteImpl/GroupByKey/Reify into
write/Write/WriteImpl/WindowInto(WindowIntoFn)
root: INFO: 2018-05-14T19:06:45.918Z: JOB_MESSAGE_DETAILED: Fusing consumer
split into read/Read
root: INFO: 2018-05-14T19:06:45.954Z: JOB_MESSAGE_DETAILED: Fusing consumer
group/Reify into pair_with_one
root: INFO: 2018-05-14T19:06:46Z: JOB_MESSAGE_DETAILED: Fusing consumer
write/Write/WriteImpl/Pair into write/Write/WriteImpl/WriteBundles/WriteBundles
root: INFO: 2018-05-14T19:06:46.039Z: JOB_MESSAGE_DETAILED: Fusing consumer
write/Write/WriteImpl/WriteBundles/WriteBundles into format
root: INFO: 2018-05-14T19:06:46.076Z: JOB_MESSAGE_DETAILED: Fusing consumer
pair_with_one into split
root: INFO: 2018-05-14T19:06:46.114Z: JOB_MESSAGE_DETAILED: Fusing consumer
count into group/GroupByWindow
root: INFO: 2018-05-14T19:06:46.167Z: JOB_MESSAGE_DETAILED: Fusing consumer
format into count
root: INFO: 2018-05-14T19:06:46.203Z: JOB_MESSAGE_DETAILED: Fusing consumer
write/Write/WriteImpl/InitializeWrite into write/Write/WriteImpl/DoOnce/Read
root: INFO: 2018-05-14T19:06:46.240Z: JOB_MESSAGE_DEBUG: Workflow config is
missing a default resource spec.
root: INFO: 2018-05-14T19:06:46.276Z: JOB_MESSAGE_DEBUG: Adding StepResource
setup and teardown to workflow graph.
root: INFO: 2018-05-14T19:06:46.314Z: JOB_MESSAGE_DEBUG: Adding workflow start
and stop steps.
root: INFO: 2018-05-14T19:06:46.374Z: JOB_MESSAGE_DEBUG: Assigning stage ids.
root: INFO: 2018-05-14T19:06:46.567Z: JOB_MESSAGE_DEBUG: Executing wait step
start26
root: INFO: 2018-05-14T19:06:46.656Z: JOB_MESSAGE_BASIC: Executing operation
write/Write/WriteImpl/DoOnce/Read+write/Write/WriteImpl/InitializeWrite
root: INFO: 2018-05-14T19:06:46.692Z: JOB_MESSAGE_BASIC: Executing operation
write/Write/WriteImpl/GroupByKey/Create
root: INFO: 2018-05-14T19:06:46.720Z: JOB_MESSAGE_DEBUG: Starting worker pool
setup.
root: INFO: 2018-05-14T19:06:46.748Z: JOB_MESSAGE_BASIC: Executing operation
group/Create
root: INFO: 2018-05-14T19:06:46.782Z: JOB_MESSAGE_BASIC: Starting 1 workers in
us-central1-b...
root: INFO: 2018-05-14T19:06:46.876Z: JOB_MESSAGE_DEBUG: Value
"write/Write/WriteImpl/GroupByKey/Session" materialized.
root: INFO: 2018-05-14T19:06:46.912Z: JOB_MESSAGE_DEBUG: Value "group/Session"
materialized.
root: INFO: 2018-05-14T19:06:46.991Z: JOB_MESSAGE_BASIC: Executing operation
read/Read+split+pair_with_one+group/Reify+group/Write
root: INFO: 2018-05-14T19:06:54.362Z: JOB_MESSAGE_DETAILED: Autoscaling: Raised
the number of workers to 0 based on the rate of progress in the currently
running step(s).
root: INFO: 2018-05-14T19:07:04.933Z: JOB_MESSAGE_DETAILED: Autoscaling: Raised
the number of workers to 1 based on the rate of progress in the currently
running step(s).
root: INFO: 2018-05-14T19:07:28.289Z: JOB_MESSAGE_DETAILED: Workers have
started successfully.
root: INFO: 2018-05-14T19:14:49.484Z: JOB_MESSAGE_BASIC: Autoscaling: Resizing
worker pool from 1 to 5.
root: INFO: 2018-05-14T19:14:55.099Z: JOB_MESSAGE_DETAILED: Autoscaling: Raised
the number of workers to 5 based on the rate of progress in the currently
running step(s).
root: INFO: 2018-05-14T19:18:19.674Z: JOB_MESSAGE_BASIC: Autoscaling: Resizing
worker pool from 5 to 6.
root: INFO: 2018-05-14T19:18:25.254Z: JOB_MESSAGE_DETAILED: Autoscaling: Raised
the number of workers to 6 based on the rate of progress in the currently
running step(s).
root: INFO: 2018-05-14T19:20:49.589Z: JOB_MESSAGE_BASIC: Autoscaling: Resizing
worker pool from 6 to 7.
root: INFO: 2018-05-14T19:20:55.228Z: JOB_MESSAGE_DETAILED: Autoscaling: Raised
the number of workers to 7 based on the rate of progress in the currently
running step(s).
root: INFO: 2018-05-14T19:23:19.588Z: JOB_MESSAGE_BASIC: Autoscaling: Resizing
worker pool from 7 to 8.
root: INFO: 2018-05-14T19:23:25.289Z: JOB_MESSAGE_DETAILED: Autoscaling: Raised
the number of workers to 8 based on the rate of progress in the currently
running step(s).
root: INFO: 2018-05-14T19:23:25.842Z: JOB_MESSAGE_DEBUG: Executing failure step
failure25
root: INFO: 2018-05-14T19:23:25.884Z: JOB_MESSAGE_ERROR: Workflow failed.
Causes:
S01:write/Write/WriteImpl/DoOnce/Read+write/Write/WriteImpl/InitializeWrite
failed., A work item was attempted 4 times without success. Each time the
worker eventually lost contact with the service. The work item was attempted
on:
beamapp-jenkins-051419063-05141206-k7j2-harness-vzb1,
beamapp-jenkins-051419063-05141206-k7j2-harness-vzb1,
beamapp-jenkins-051419063-05141206-k7j2-harness-vzb1,
beamapp-jenkins-051419063-05141206-k7j2-harness-vzb1
root: INFO: 2018-05-14T19:23:26.061Z: JOB_MESSAGE_DETAILED: Cleaning up.
root: INFO: 2018-05-14T19:23:26.154Z: JOB_MESSAGE_DEBUG: Starting worker pool
teardown.
root: INFO: 2018-05-14T19:23:26.197Z: JOB_MESSAGE_BASIC: Stopping worker pool...
root: INFO: 2018-05-14T19:25:35.019Z: JOB_MESSAGE_DETAILED: Autoscaling:
Reduced the number of workers to 0 based on the rate of progress in the
currently running step(s).
root: INFO: 2018-05-14T19:25:35.096Z: JOB_MESSAGE_DEBUG: Tearing down pending
resources...
root: INFO: Job 2018-05-14_12_06_39-1090332721983279184 is in state
JOB_STATE_FAILED
--------------------- >> end captured logging << ---------------------
----------------------------------------------------------------------
XML:
<https://builds.apache.org/job/beam_PostCommit_Python_Verify/ws/src/sdks/python/nosetests.xml>
----------------------------------------------------------------------
Ran 4 tests in 1150.504s
FAILED (errors=3)
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-05-14_12_06_39-1241584300338533466?project=apache-beam-testing.
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-05-14_12_06_49-766806658121866692?project=apache-beam-testing.
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-05-14_12_06_39-4892659135274416519?project=apache-beam-testing.
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-05-14_12_06_39-1090332721983279184?project=apache-beam-testing.
> Task :beam-sdks-python:validatesRunnerTests FAILED
:beam-sdks-python:validatesRunnerTests (Thread[Task worker for ':',5,main])
completed. Took 19 mins 12.054 secs.
FAILURE: Build completed with 2 failures.
1: Task failed with an exception.
-----------
* Where:
Build file
'<https://builds.apache.org/job/beam_PostCommit_Python_Verify/ws/src/sdks/python/build.gradle'>
line: 102
* What went wrong:
Execution failed for task ':beam-sdks-python:lintPy3'.
> 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.
==============================================================================
2: Task failed with an exception.
-----------
* Where:
Build file
'<https://builds.apache.org/job/beam_PostCommit_Python_Verify/ws/src/sdks/python/build.gradle'>
line: 192
* What went wrong:
Execution failed for task ':beam-sdks-python:validatesRunnerTests'.
> 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
BUILD FAILED in 46m 6s
11 actionable tasks: 11 executed
Publishing build scan...
https://gradle.com/s/7o5eiomo5nyvm
Build step 'Invoke Gradle script' changed build result to FAILURE
Build step 'Invoke Gradle script' marked build as failure
