See
<https://builds.apache.org/job/beam_PostCommit_Python_ValidatesRunner_Dataflow/1154/display/redirect>
------------------------------------------
[...truncated 772.71 KB...]
"properties": {
"display_data": [
{
"key": "fn",
"label": "Transform Function",
"namespace": "apache_beam.transforms.core.CallableWrapperDoFn",
"type": "STRING",
"value": "<lambda>"
},
{
"key": "fn",
"label": "Transform Function",
"namespace": "apache_beam.transforms.core.ParDo",
"shortValue": "CallableWrapperDoFn",
"type": "STRING",
"value": "apache_beam.transforms.core.CallableWrapperDoFn"
}
],
"non_parallel_inputs": {},
"output_info": [
{
"encoding": {
"@type": "kind:windowed_value",
"component_encodings": [
{
"@type": "kind:pair",
"component_encodings": [
{
"@type": "kind:bytes"
},
{
"@type": "kind:bytes"
}
],
"is_pair_like": true
},
{
"@type": "kind:global_window"
}
],
"is_wrapper": true
},
"output_name": "out",
"user_name": "FlatMap(<lambda at
sideinputs_test.py:302>)/MapToVoidKey0.out"
}
],
"parallel_input": {
"@type": "OutputReference",
"output_name": "out",
"step_name": "s4"
},
"serialized_fn": "<string of 968 bytes>",
"user_name": "FlatMap(<lambda at sideinputs_test.py:302>)/MapToVoidKey0"
}
}
],
"type": "JOB_TYPE_BATCH"
}
root: INFO: Create job: <Job
createTime: u'2018-03-21T09:23:34.649841Z'
currentStateTime: u'1970-01-01T00:00:00Z'
id: u'2018-03-21_02_23_33-14591425413892706420'
location: u'us-central1'
name: u'beamapp-jenkins-0321092317-716777'
projectId: u'apache-beam-testing'
stageStates: []
steps: []
tempFiles: []
type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)>
root: INFO: Created job with id: [2018-03-21_02_23_33-14591425413892706420]
root: INFO: To access the Dataflow monitoring console, please navigate to
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_23_33-14591425413892706420?project=apache-beam-testing
root: INFO: Job 2018-03-21_02_23_33-14591425413892706420 is in state
JOB_STATE_PENDING
root: INFO: 2018-03-21T09:23:33.835Z: JOB_MESSAGE_WARNING: Job
2018-03-21_02_23_33-14591425413892706420 might autoscale up to 250 workers.
root: INFO: 2018-03-21T09:23:33.854Z: JOB_MESSAGE_DETAILED: Autoscaling is
enabled for job 2018-03-21_02_23_33-14591425413892706420. The number of workers
will be between 1 and 250.
root: INFO: 2018-03-21T09:23:33.864Z: JOB_MESSAGE_DETAILED: Autoscaling was
automatically enabled for job 2018-03-21_02_23_33-14591425413892706420.
root: INFO: 2018-03-21T09:23:36.410Z: JOB_MESSAGE_DETAILED: Checking required
Cloud APIs are enabled.
root: INFO: 2018-03-21T09:23:36.714Z: JOB_MESSAGE_DETAILED: Checking
permissions granted to controller Service Account.
root: INFO: 2018-03-21T09:23:37.465Z: JOB_MESSAGE_DETAILED: Expanding
CoGroupByKey operations into optimizable parts.
root: INFO: 2018-03-21T09:23:37.499Z: JOB_MESSAGE_DEBUG: Combiner lifting
skipped for step assert_that/Group/GroupByKey: GroupByKey not followed by a
combiner.
root: INFO: 2018-03-21T09:23:37.533Z: JOB_MESSAGE_DETAILED: Expanding
GroupByKey operations into optimizable parts.
root: INFO: 2018-03-21T09:23:37.559Z: JOB_MESSAGE_DETAILED: Lifting
ValueCombiningMappingFns into MergeBucketsMappingFns
root: INFO: 2018-03-21T09:23:37.576Z: JOB_MESSAGE_DEBUG: Annotating graph with
Autotuner information.
root: INFO: 2018-03-21T09:23:37.612Z: JOB_MESSAGE_DETAILED: Fusing adjacent
ParDo, Read, Write, and Flatten operations
root: INFO: 2018-03-21T09:23:37.635Z: JOB_MESSAGE_DETAILED: Unzipping flatten
s13 for input s11.out
root: INFO: 2018-03-21T09:23:37.658Z: JOB_MESSAGE_DETAILED: Fusing unzipped
copy of assert_that/Group/GroupByKey/Reify, through flatten
assert_that/Group/Flatten, into producer assert_that/Group/pair_with_0
root: INFO: 2018-03-21T09:23:37.680Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Group/GroupByKey/GroupByWindow into
assert_that/Group/GroupByKey/Read
root: INFO: 2018-03-21T09:23:37.710Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Group/Map(_merge_tagged_vals_under_key) into
assert_that/Group/GroupByKey/GroupByWindow
root: INFO: 2018-03-21T09:23:37.735Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Unkey into assert_that/Group/Map(_merge_tagged_vals_under_key)
root: INFO: 2018-03-21T09:23:37.758Z: JOB_MESSAGE_DETAILED: Unzipping flatten
s13-u13 for input s14-reify-value0-c11
root: INFO: 2018-03-21T09:23:37.784Z: JOB_MESSAGE_DETAILED: Fusing unzipped
copy of assert_that/Group/GroupByKey/Write, through flatten s13-u13, into
producer assert_that/Group/GroupByKey/Reify
root: INFO: 2018-03-21T09:23:37.811Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Match into assert_that/Unkey
root: INFO: 2018-03-21T09:23:37.831Z: JOB_MESSAGE_DETAILED: Created new flatten
s4-c17 to unzip producers of s18
root: INFO: 2018-03-21T09:23:37.864Z: JOB_MESSAGE_DETAILED: Unzipping flatten
s4-c17 for input s2.out
root: INFO: 2018-03-21T09:23:37.893Z: JOB_MESSAGE_DETAILED: Fusing unzipped
copy of FlatMap(<lambda at sideinputs_test.py:302>)/MapToVoidKey0, through
flatten Flatten, into producer side1/Read
root: INFO: 2018-03-21T09:23:37.931Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Group/pair_with_0 into assert_that/Create/Read
root: INFO: 2018-03-21T09:23:37.960Z: JOB_MESSAGE_DETAILED: Unzipping flatten
s4 for input s2.out
root: INFO: 2018-03-21T09:23:37.992Z: JOB_MESSAGE_DETAILED: Fusing unzipped
copy of FlatMap(<lambda at sideinputs_test.py:302>)/MapToVoidKey0, through
flatten Flatten, into producer side1/Read
root: INFO: 2018-03-21T09:23:38.025Z: JOB_MESSAGE_DETAILED: Fusing consumer
FlatMap(<lambda at sideinputs_test.py:302>)/MapToVoidKey0 into side2/Read
root: INFO: 2018-03-21T09:23:38.046Z: JOB_MESSAGE_DETAILED: Fusing consumer
FlatMap(<lambda at sideinputs_test.py:302>)/MapToVoidKey0 into side2/Read
root: INFO: 2018-03-21T09:23:38.072Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Group/GroupByKey/Write into assert_that/Group/GroupByKey/Reify
root: INFO: 2018-03-21T09:23:38.104Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Group/GroupByKey/Reify into assert_that/Group/pair_with_1
root: INFO: 2018-03-21T09:23:38.134Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/ToVoidKey into assert_that/WindowInto(WindowIntoFn)
root: INFO: 2018-03-21T09:23:38.161Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/WindowInto(WindowIntoFn) into FlatMap(<lambda at
sideinputs_test.py:302>)/FlatMap(<lambda at sideinputs_test.py:302>)
root: INFO: 2018-03-21T09:23:38.179Z: JOB_MESSAGE_DETAILED: Fusing consumer
FlatMap(<lambda at sideinputs_test.py:302>)/FlatMap(<lambda at
sideinputs_test.py:302>) into main input/Read
root: INFO: 2018-03-21T09:23:38.209Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Group/pair_with_1 into assert_that/ToVoidKey
root: INFO: 2018-03-21T09:23:38.232Z: JOB_MESSAGE_DEBUG: Workflow config is
missing a default resource spec.
root: INFO: 2018-03-21T09:23:38.262Z: JOB_MESSAGE_DEBUG: Adding StepResource
setup and teardown to workflow graph.
root: INFO: 2018-03-21T09:23:38.295Z: JOB_MESSAGE_DEBUG: Adding workflow start
and stop steps.
root: INFO: 2018-03-21T09:23:38.327Z: JOB_MESSAGE_DEBUG: Assigning stage ids.
root: INFO: 2018-03-21T09:23:38.483Z: JOB_MESSAGE_DEBUG: Executing wait step
start35
root: INFO: 2018-03-21T09:23:38.542Z: JOB_MESSAGE_BASIC: Executing operation
side2/Read+FlatMap(<lambda at
sideinputs_test.py:302>)/MapToVoidKey0+FlatMap(<lambda at
sideinputs_test.py:302>)/MapToVoidKey0
root: INFO: 2018-03-21T09:23:38.573Z: JOB_MESSAGE_BASIC: Executing operation
side1/Read+FlatMap(<lambda at
sideinputs_test.py:302>)/MapToVoidKey0+FlatMap(<lambda at
sideinputs_test.py:302>)/MapToVoidKey0
root: INFO: 2018-03-21T09:23:38.585Z: JOB_MESSAGE_DEBUG: Starting worker pool
setup.
root: INFO: 2018-03-21T09:23:38.587Z: JOB_MESSAGE_BASIC: Executing operation
assert_that/Group/GroupByKey/Create
root: INFO: 2018-03-21T09:23:38.619Z: JOB_MESSAGE_BASIC: Starting 1 workers in
us-central1-f...
root: INFO: 2018-03-21T09:23:38.705Z: JOB_MESSAGE_DEBUG: Value
"assert_that/Group/GroupByKey/Session" materialized.
root: INFO: 2018-03-21T09:23:38.775Z: JOB_MESSAGE_BASIC: Executing operation
assert_that/Create/Read+assert_that/Group/pair_with_0+assert_that/Group/GroupByKey/Reify+assert_that/Group/GroupByKey/Write
root: INFO: Job 2018-03-21_02_23_33-14591425413892706420 is in state
JOB_STATE_RUNNING
root: INFO: 2018-03-21T09:23:48.721Z: 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-03-21T09:24:04.684Z: 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-03-21T09:24:20.134Z: JOB_MESSAGE_DETAILED: Workers have
started successfully.
root: INFO: 2018-03-21T09:28:49.093Z: JOB_MESSAGE_DEBUG: Value "FlatMap(<lambda
at sideinputs_test.py:302>)/MapToVoidKey0.out" materialized.
root: INFO: 2018-03-21T09:28:56.271Z: JOB_MESSAGE_DEBUG: Value "FlatMap(<lambda
at sideinputs_test.py:302>)/MapToVoidKey0.out" materialized.
root: INFO: 2018-03-21T09:28:56.339Z: JOB_MESSAGE_BASIC: Executing operation
s4-u26
root: INFO: 2018-03-21T09:28:56.509Z: JOB_MESSAGE_DEBUG: Value "FlatMap(<lambda
at sideinputs_test.py:302>)/MapToVoidKey0.out" materialized.
root: INFO: 2018-03-21T09:28:56.568Z: JOB_MESSAGE_BASIC: Executing operation
FlatMap(<lambda at
sideinputs_test.py:302>)/_DataflowIterableSideInput(MapToVoidKey0.out.0)
root: INFO: 2018-03-21T09:28:56.681Z: JOB_MESSAGE_DEBUG: Value "FlatMap(<lambda
at
sideinputs_test.py:302>)/_DataflowIterableSideInput(MapToVoidKey0.out.0).output"
materialized.
root: INFO: 2018-03-21T09:28:56.784Z: JOB_MESSAGE_BASIC: Executing operation
main input/Read+FlatMap(<lambda at sideinputs_test.py:302>)/FlatMap(<lambda at
sideinputs_test.py:302>)+assert_that/WindowInto(WindowIntoFn)+assert_that/ToVoidKey+assert_that/Group/pair_with_1+assert_that/Group/GroupByKey/Reify+assert_that/Group/GroupByKey/Write
root: INFO: 2018-03-21T09:29:01.372Z: JOB_MESSAGE_ERROR: Traceback (most recent
call last):
File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/batchworker.py",
line 609, in do_work
work_executor.execute()
File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/executor.py",
line 167, in execute
op.start()
File "apache_beam/runners/worker/operations.py", line 340, in
apache_beam.runners.worker.operations.DoOperation.start
def start(self):
File "apache_beam/runners/worker/operations.py", line 341, in
apache_beam.runners.worker.operations.DoOperation.start
with self.scoped_start_state:
File "apache_beam/runners/worker/operations.py", line 373, in
apache_beam.runners.worker.operations.DoOperation.start
self.dofn_runner = common.DoFnRunner(
File "apache_beam/runners/common.py", line 483, in
apache_beam.runners.common.DoFnRunner.__init__
self.do_fn_invoker = DoFnInvoker.create_invoker(
File "apache_beam/runners/common.py", line 203, in
apache_beam.runners.common.DoFnInvoker.create_invoker
return PerWindowInvoker(
File "apache_beam/runners/common.py", line 313, in
apache_beam.runners.common.PerWindowInvoker.__init__
input_args, input_kwargs, [si[global_window] for si in side_inputs])
File
"/usr/local/lib/python2.7/dist-packages/apache_beam/transforms/sideinputs.py",
line 62, in __getitem__
self._cache[window] = self._view_class._from_runtime_iterable(
AttributeError: type object '_DataflowIterableSideInput' has no attribute
'_from_runtime_iterable'
root: INFO: 2018-03-21T09:29:04.774Z: JOB_MESSAGE_ERROR: Traceback (most recent
call last):
File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/batchworker.py",
line 609, in do_work
work_executor.execute()
File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/executor.py",
line 167, in execute
op.start()
File "apache_beam/runners/worker/operations.py", line 340, in
apache_beam.runners.worker.operations.DoOperation.start
def start(self):
File "apache_beam/runners/worker/operations.py", line 341, in
apache_beam.runners.worker.operations.DoOperation.start
with self.scoped_start_state:
File "apache_beam/runners/worker/operations.py", line 373, in
apache_beam.runners.worker.operations.DoOperation.start
self.dofn_runner = common.DoFnRunner(
File "apache_beam/runners/common.py", line 483, in
apache_beam.runners.common.DoFnRunner.__init__
self.do_fn_invoker = DoFnInvoker.create_invoker(
File "apache_beam/runners/common.py", line 203, in
apache_beam.runners.common.DoFnInvoker.create_invoker
return PerWindowInvoker(
File "apache_beam/runners/common.py", line 313, in
apache_beam.runners.common.PerWindowInvoker.__init__
input_args, input_kwargs, [si[global_window] for si in side_inputs])
File
"/usr/local/lib/python2.7/dist-packages/apache_beam/transforms/sideinputs.py",
line 62, in __getitem__
self._cache[window] = self._view_class._from_runtime_iterable(
AttributeError: type object '_DataflowIterableSideInput' has no attribute
'_from_runtime_iterable'
root: INFO: 2018-03-21T09:29:08.146Z: JOB_MESSAGE_ERROR: Traceback (most recent
call last):
File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/batchworker.py",
line 609, in do_work
work_executor.execute()
File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/executor.py",
line 167, in execute
op.start()
File "apache_beam/runners/worker/operations.py", line 340, in
apache_beam.runners.worker.operations.DoOperation.start
def start(self):
File "apache_beam/runners/worker/operations.py", line 341, in
apache_beam.runners.worker.operations.DoOperation.start
with self.scoped_start_state:
File "apache_beam/runners/worker/operations.py", line 373, in
apache_beam.runners.worker.operations.DoOperation.start
self.dofn_runner = common.DoFnRunner(
File "apache_beam/runners/common.py", line 483, in
apache_beam.runners.common.DoFnRunner.__init__
self.do_fn_invoker = DoFnInvoker.create_invoker(
File "apache_beam/runners/common.py", line 203, in
apache_beam.runners.common.DoFnInvoker.create_invoker
return PerWindowInvoker(
File "apache_beam/runners/common.py", line 313, in
apache_beam.runners.common.PerWindowInvoker.__init__
input_args, input_kwargs, [si[global_window] for si in side_inputs])
File
"/usr/local/lib/python2.7/dist-packages/apache_beam/transforms/sideinputs.py",
line 62, in __getitem__
self._cache[window] = self._view_class._from_runtime_iterable(
AttributeError: type object '_DataflowIterableSideInput' has no attribute
'_from_runtime_iterable'
root: INFO: 2018-03-21T09:29:11.506Z: JOB_MESSAGE_ERROR: Traceback (most recent
call last):
File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/batchworker.py",
line 609, in do_work
work_executor.execute()
File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/executor.py",
line 167, in execute
op.start()
File "apache_beam/runners/worker/operations.py", line 340, in
apache_beam.runners.worker.operations.DoOperation.start
def start(self):
File "apache_beam/runners/worker/operations.py", line 341, in
apache_beam.runners.worker.operations.DoOperation.start
with self.scoped_start_state:
File "apache_beam/runners/worker/operations.py", line 373, in
apache_beam.runners.worker.operations.DoOperation.start
self.dofn_runner = common.DoFnRunner(
File "apache_beam/runners/common.py", line 483, in
apache_beam.runners.common.DoFnRunner.__init__
self.do_fn_invoker = DoFnInvoker.create_invoker(
File "apache_beam/runners/common.py", line 203, in
apache_beam.runners.common.DoFnInvoker.create_invoker
return PerWindowInvoker(
File "apache_beam/runners/common.py", line 313, in
apache_beam.runners.common.PerWindowInvoker.__init__
input_args, input_kwargs, [si[global_window] for si in side_inputs])
File
"/usr/local/lib/python2.7/dist-packages/apache_beam/transforms/sideinputs.py",
line 62, in __getitem__
self._cache[window] = self._view_class._from_runtime_iterable(
AttributeError: type object '_DataflowIterableSideInput' has no attribute
'_from_runtime_iterable'
root: INFO: 2018-03-21T09:29:11.541Z: JOB_MESSAGE_DEBUG: Executing failure step
failure34
root: INFO: 2018-03-21T09:29:11.561Z: JOB_MESSAGE_ERROR: Workflow failed.
Causes: S07:main input/Read+FlatMap(<lambda at
sideinputs_test.py:302>)/FlatMap(<lambda at
sideinputs_test.py:302>)+assert_that/WindowInto(WindowIntoFn)+assert_that/ToVoidKey+assert_that/Group/pair_with_1+assert_that/Group/GroupByKey/Reify+assert_that/Group/GroupByKey/Write
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-032109231-03210223-9a1d-harness-7p4b,
beamapp-jenkins-032109231-03210223-9a1d-harness-7p4b,
beamapp-jenkins-032109231-03210223-9a1d-harness-7p4b,
beamapp-jenkins-032109231-03210223-9a1d-harness-7p4b
root: INFO: 2018-03-21T09:29:11.654Z: JOB_MESSAGE_DETAILED: Cleaning up.
root: INFO: 2018-03-21T09:29:11.705Z: JOB_MESSAGE_DEBUG: Starting worker pool
teardown.
root: INFO: 2018-03-21T09:29:11.723Z: JOB_MESSAGE_BASIC: Stopping worker pool...
root: INFO: 2018-03-21T09:30:46.394Z: 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-03-21T09:30:46.450Z: JOB_MESSAGE_DEBUG: Tearing down pending
resources...
root: INFO: Job 2018-03-21_02_23_33-14591425413892706420 is in state
JOB_STATE_FAILED
--------------------- >> end captured logging << ---------------------
----------------------------------------------------------------------
Ran 16 tests in 1761.429s
FAILED (errors=9)
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_02_01-2996790095827568519?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_09_26-7007083816217557564?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_16_50-4252351749116239500?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_23_33-14591425413892706420?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_02_01-13486255441380947868?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_09_29-11914139163787443870?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_16_47-15265781212626098920?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_23_43-16787164454934167576?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_02_01-273260708924737844?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_09_25-4022298251868897173?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_16_57-1494208609340969065?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_23_34-1982910996006354492?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_02_01-17710928084807007534?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_09_13-12024347560072517842?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_15_50-1275714982770006302?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_02_22_55-11126691381800298409?project=apache-beam-testing
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user [email protected]
Not sending mail to unregistered user [email protected]
