See
<https://builds.apache.org/job/beam_PostCommit_Python_ValidatesRunner_Dataflow/1160/display/redirect>
------------------------------------------
[...truncated 773.49 KB...]
"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-22T07:21:20.408300Z'
currentStateTime: u'1970-01-01T00:00:00Z'
id: u'2018-03-22_00_21_19-17528082924397423682'
location: u'us-central1'
name: u'beamapp-jenkins-0322072111-480675'
projectId: u'apache-beam-testing'
stageStates: []
steps: []
tempFiles: []
type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)>
root: INFO: Created job with id: [2018-03-22_00_21_19-17528082924397423682]
root: INFO: To access the Dataflow monitoring console, please navigate to
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_21_19-17528082924397423682?project=apache-beam-testing
root: INFO: Job 2018-03-22_00_21_19-17528082924397423682 is in state
JOB_STATE_PENDING
root: INFO: 2018-03-22T07:21:19.253Z: JOB_MESSAGE_WARNING: Job
2018-03-22_00_21_19-17528082924397423682 might autoscale up to 250 workers.
root: INFO: 2018-03-22T07:21:19.286Z: JOB_MESSAGE_DETAILED: Autoscaling is
enabled for job 2018-03-22_00_21_19-17528082924397423682. The number of workers
will be between 1 and 250.
root: INFO: 2018-03-22T07:21:19.315Z: JOB_MESSAGE_DETAILED: Autoscaling was
automatically enabled for job 2018-03-22_00_21_19-17528082924397423682.
root: INFO: 2018-03-22T07:21:22.679Z: JOB_MESSAGE_DETAILED: Checking required
Cloud APIs are enabled.
root: INFO: 2018-03-22T07:21:22.804Z: JOB_MESSAGE_DETAILED: Checking
permissions granted to controller Service Account.
root: INFO: 2018-03-22T07:21:23.696Z: JOB_MESSAGE_DETAILED: Expanding
CoGroupByKey operations into optimizable parts.
root: INFO: 2018-03-22T07:21:23.758Z: JOB_MESSAGE_DEBUG: Combiner lifting
skipped for step assert_that/Group/GroupByKey: GroupByKey not followed by a
combiner.
root: INFO: 2018-03-22T07:21:23.789Z: JOB_MESSAGE_DETAILED: Expanding
GroupByKey operations into optimizable parts.
root: INFO: 2018-03-22T07:21:23.823Z: JOB_MESSAGE_DETAILED: Lifting
ValueCombiningMappingFns into MergeBucketsMappingFns
root: INFO: 2018-03-22T07:21:23.871Z: JOB_MESSAGE_DEBUG: Annotating graph with
Autotuner information.
root: INFO: 2018-03-22T07:21:23.934Z: JOB_MESSAGE_DETAILED: Fusing adjacent
ParDo, Read, Write, and Flatten operations
root: INFO: 2018-03-22T07:21:23.970Z: JOB_MESSAGE_DETAILED: Unzipping flatten
s13 for input s11.out
root: INFO: 2018-03-22T07:21:24Z: 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-22T07:21:24.038Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Group/GroupByKey/GroupByWindow into
assert_that/Group/GroupByKey/Read
root: INFO: 2018-03-22T07:21:24.072Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Group/Map(_merge_tagged_vals_under_key) into
assert_that/Group/GroupByKey/GroupByWindow
root: INFO: 2018-03-22T07:21:24.104Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Unkey into assert_that/Group/Map(_merge_tagged_vals_under_key)
root: INFO: 2018-03-22T07:21:24.144Z: JOB_MESSAGE_DETAILED: Unzipping flatten
s13-u13 for input s14-reify-value0-c11
root: INFO: 2018-03-22T07:21:24.182Z: 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-22T07:21:24.210Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Match into assert_that/Unkey
root: INFO: 2018-03-22T07:21:24.245Z: JOB_MESSAGE_DETAILED: Created new flatten
s4-c17 to unzip producers of s18
root: INFO: 2018-03-22T07:21:24.274Z: JOB_MESSAGE_DETAILED: Unzipping flatten
s4-c17 for input s2.out
root: INFO: 2018-03-22T07:21:24.304Z: 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-22T07:21:24.331Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Group/pair_with_0 into assert_that/Create/Read
root: INFO: 2018-03-22T07:21:24.371Z: JOB_MESSAGE_DETAILED: Unzipping flatten
s4 for input s2.out
root: INFO: 2018-03-22T07:21:24.403Z: 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-22T07:21:24.429Z: JOB_MESSAGE_DETAILED: Fusing consumer
FlatMap(<lambda at sideinputs_test.py:302>)/MapToVoidKey0 into side2/Read
root: INFO: 2018-03-22T07:21:24.461Z: JOB_MESSAGE_DETAILED: Fusing consumer
FlatMap(<lambda at sideinputs_test.py:302>)/MapToVoidKey0 into side2/Read
root: INFO: 2018-03-22T07:21:24.483Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Group/GroupByKey/Write into assert_that/Group/GroupByKey/Reify
root: INFO: 2018-03-22T07:21:24.506Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Group/GroupByKey/Reify into assert_that/Group/pair_with_1
root: INFO: 2018-03-22T07:21:24.537Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/ToVoidKey into assert_that/WindowInto(WindowIntoFn)
root: INFO: 2018-03-22T07:21:24.575Z: 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-22T07:21:24.612Z: 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-22T07:21:24.646Z: JOB_MESSAGE_DETAILED: Fusing consumer
assert_that/Group/pair_with_1 into assert_that/ToVoidKey
root: INFO: 2018-03-22T07:21:24.680Z: JOB_MESSAGE_DEBUG: Workflow config is
missing a default resource spec.
root: INFO: 2018-03-22T07:21:24.710Z: JOB_MESSAGE_DEBUG: Adding StepResource
setup and teardown to workflow graph.
root: INFO: 2018-03-22T07:21:24.746Z: JOB_MESSAGE_DEBUG: Adding workflow start
and stop steps.
root: INFO: 2018-03-22T07:21:24.790Z: JOB_MESSAGE_DEBUG: Assigning stage ids.
root: INFO: 2018-03-22T07:21:24.976Z: JOB_MESSAGE_DEBUG: Executing wait step
start35
root: INFO: 2018-03-22T07:21:25.063Z: 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-22T07:21:25.111Z: 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-22T07:21:25.123Z: JOB_MESSAGE_DEBUG: Starting worker pool
setup.
root: INFO: 2018-03-22T07:21:25.143Z: JOB_MESSAGE_BASIC: Executing operation
assert_that/Group/GroupByKey/Create
root: INFO: 2018-03-22T07:21:25.151Z: JOB_MESSAGE_BASIC: Starting 1 workers in
us-central1-f...
root: INFO: 2018-03-22T07:21:25.265Z: JOB_MESSAGE_DEBUG: Value
"assert_that/Group/GroupByKey/Session" materialized.
root: INFO: 2018-03-22T07:21:25.358Z: 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-22_00_21_19-17528082924397423682 is in state
JOB_STATE_RUNNING
root: INFO: 2018-03-22T07:21:32.899Z: 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-22T07:21:48.846Z: 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-22T07:22:08.214Z: JOB_MESSAGE_DETAILED: Workers have
started successfully.
root: INFO: 2018-03-22T07:26:27.319Z: JOB_MESSAGE_DEBUG: Value "FlatMap(<lambda
at sideinputs_test.py:302>)/MapToVoidKey0.out" materialized.
root: INFO: 2018-03-22T07:26:30.769Z: JOB_MESSAGE_DEBUG: Value "FlatMap(<lambda
at sideinputs_test.py:302>)/MapToVoidKey0.out" materialized.
root: INFO: 2018-03-22T07:26:30.837Z: JOB_MESSAGE_BASIC: Executing operation
s4-u26
root: INFO: 2018-03-22T07:26:31Z: JOB_MESSAGE_DEBUG: Value "FlatMap(<lambda at
sideinputs_test.py:302>)/MapToVoidKey0.out" materialized.
root: INFO: 2018-03-22T07:26:31.070Z: JOB_MESSAGE_BASIC: Executing operation
FlatMap(<lambda at
sideinputs_test.py:302>)/_DataflowIterableSideInput(MapToVoidKey0.out.0)
root: INFO: 2018-03-22T07:26:31.192Z: JOB_MESSAGE_DEBUG: Value "FlatMap(<lambda
at
sideinputs_test.py:302>)/_DataflowIterableSideInput(MapToVoidKey0.out.0).output"
materialized.
root: INFO: 2018-03-22T07:26:31.260Z: 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-22T07:26:39.872Z: 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 339, in
apache_beam.runners.worker.operations.DoOperation.start
def start(self):
File "apache_beam/runners/worker/operations.py", line 340, in
apache_beam.runners.worker.operations.DoOperation.start
with self.scoped_start_state:
File "apache_beam/runners/worker/operations.py", line 372, 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-22T07:26:43.279Z: 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 339, in
apache_beam.runners.worker.operations.DoOperation.start
def start(self):
File "apache_beam/runners/worker/operations.py", line 340, in
apache_beam.runners.worker.operations.DoOperation.start
with self.scoped_start_state:
File "apache_beam/runners/worker/operations.py", line 372, 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-22T07:26:46.686Z: 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 339, in
apache_beam.runners.worker.operations.DoOperation.start
def start(self):
File "apache_beam/runners/worker/operations.py", line 340, in
apache_beam.runners.worker.operations.DoOperation.start
with self.scoped_start_state:
File "apache_beam/runners/worker/operations.py", line 372, 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-22T07:26:50.122Z: 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 339, in
apache_beam.runners.worker.operations.DoOperation.start
def start(self):
File "apache_beam/runners/worker/operations.py", line 340, in
apache_beam.runners.worker.operations.DoOperation.start
with self.scoped_start_state:
File "apache_beam/runners/worker/operations.py", line 372, 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-22T07:26:50.188Z: JOB_MESSAGE_DEBUG: Executing failure step
failure34
root: INFO: 2018-03-22T07:26:50.221Z: 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-032207211-03220021-65bf-harness-66vv,
beamapp-jenkins-032207211-03220021-65bf-harness-66vv,
beamapp-jenkins-032207211-03220021-65bf-harness-66vv,
beamapp-jenkins-032207211-03220021-65bf-harness-66vv
root: INFO: 2018-03-22T07:26:50.352Z: JOB_MESSAGE_DETAILED: Cleaning up.
root: INFO: 2018-03-22T07:26:50.420Z: JOB_MESSAGE_DEBUG: Starting worker pool
teardown.
root: INFO: 2018-03-22T07:26:50.492Z: JOB_MESSAGE_BASIC: Stopping worker pool...
root: INFO: 2018-03-22T07:28:33.612Z: 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-22T07:28:33.694Z: JOB_MESSAGE_DEBUG: Tearing down pending
resources...
root: INFO: Job 2018-03-22_00_21_19-17528082924397423682 is in state
JOB_STATE_FAILED
--------------------- >> end captured logging << ---------------------
----------------------------------------------------------------------
Ran 16 tests in 1757.871s
FAILED (errors=9)
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_23_59_57-12214191060176784510?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_07_02-1040882100199919047?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_14_20-2998412730206497463?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_21_19-17528082924397423682?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_23_59_55-12012663067945285952?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_06_51-8527466743054230187?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_13_13-15745046601950066795?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_20_08-16446028739330626676?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_23_59_56-16216757088516890472?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_07_07-15377505959674691585?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_15_00-14367889108911716138?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_21_31-9170249606556230262?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-21_23_59_56-4772510310736419132?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_07_04-13118549833741922773?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_14_42-1984074115359518273?project=apache-beam-testing
Found:
https://console.cloud.google.com/dataflow/jobsDetail/locations/us-central1/jobs/2018-03-22_00_21_36-395739711431091741?project=apache-beam-testing
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user hero...@google.com
Not sending mail to unregistered user aal...@gmail.com
Not sending mail to unregistered user mark...@google.com
