See <https://builds.apache.org/job/beam_PostCommit_Python_Verify/863/>
------------------------------------------ [...truncated 2730 lines...] "component_encodings": [ { "@type": "PickleCoder$eJxrYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlwBmcnZOanOIA5XIYNmYyFjbSFTkh4A9DYR/g==", "component_encodings": [] }, { "@type": "PickleCoder$eJxrYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlwBmcnZOanOIA5XIYNmYyFjbSFTkh4A9DYR/g==", "component_encodings": [] } ], "is_pair_like": true }, { "@type": "TimestampCoder$eJxrYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlwhmbmpxSWJuQXOID5XIYNmYyFjbSFTkh4ANWETWg==", "component_encodings": [] }, { "@type": "PickleCoder$eJxrYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlwBmcnZOanOIA5XIYNmYyFjbSFTkh4A9DYR/g==", "component_encodings": [ { "@type": "PickleCoder$eJxrYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlwBmcnZOanOIA5XIYNmYyFjbSFTkh4A9DYR/g==", "component_encodings": [] }, { "@type": "PickleCoder$eJxrYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlwBmcnZOanOIA5XIYNmYyFjbSFTkh4A9DYR/g==", "component_encodings": [] } ], "is_pair_like": true } ], "is_wrapper": true } ] }, "output_name": "out", "user_name": "ViewAsSingleton(side.None)/CreatePCollectionView.out" } ], "parallel_input": { "@type": "OutputReference", "output_name": "out", "step_name": "s2" }, "user_name": "ViewAsSingleton(side.None)/CreatePCollectionView" } }, { "kind": "ParallelDo", "name": "s4", "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": { "s3": { "@type": "OutputReference", "output_name": "out", "step_name": "s3" } }, "output_info": [ { "encoding": { "@type": "WindowedValueCoder$<string of 408 bytes>", "component_encodings": [ { "@type": "FastPrimitivesCoder$eJxrYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/", "component_encodings": [ { "@type": "FastPrimitivesCoder$eJxrYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/", "component_encodings": [] }, { "@type": "FastPrimitivesCoder$eJxrYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/", "component_encodings": [] } ], "is_pair_like": true }, { "@type": "TimestampCoder$eJxrYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlwhmbmpxSWJuQXOID5XIYNmYyFjbSFTkh4ANWETWg==", "component_encodings": [] }, { "@type": "SingletonCoder$<string of 256 bytes>", "component_encodings": [] } ], "is_wrapper": true }, "output_name": "out", "user_name": "compute.out" } ], "parallel_input": { "@type": "OutputReference", "output_name": "out", "step_name": "s1" }, "serialized_fn": "<string of 1116 bytes>", "user_name": "compute" } } ], "type": "JOB_TYPE_BATCH" } root: INFO: Create job: <Job id: u'2016-12-12_01_48_45-8789293249756495297' projectId: u'apache-beam-testing' steps: [] tempFiles: [] type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)> root: INFO: Created job with id: [2016-12-12_01_48_45-8789293249756495297] root: INFO: To access the Dataflow monitoring console, please navigate to https://console.developers.google.com/project/apache-beam-testing/dataflow/job/2016-12-12_01_48_45-8789293249756495297 root: INFO: Job 2016-12-12_01_48_45-8789293249756495297 is in state JOB_STATE_RUNNING root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f3fa6: 2016-12-12T09:48:46.886Z: JOB_MESSAGE_DETAILED: (e6ae78f114334d3d): Checking required Cloud APIs are enabled. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f4139: 2016-12-12T09:48:47.289Z: JOB_MESSAGE_DETAILED: (e6ae78f1143343da): Expanding GroupByKey operations into optimizable parts. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f413c: 2016-12-12T09:48:47.292Z: JOB_MESSAGE_DETAILED: (e6ae78f1143341c4): Lifting ValueCombiningMappingFns into MergeBucketsMappingFns root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f413f: 2016-12-12T09:48:47.295Z: JOB_MESSAGE_DETAILED: (e6ae78f114334fae): Annotating graph with Autotuner information. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f4149: 2016-12-12T09:48:47.305Z: JOB_MESSAGE_DETAILED: (e6ae78f11433496c): Fusing adjacent ParDo, Read, Write, and Flatten operations root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f415f: 2016-12-12T09:48:47.327Z: JOB_MESSAGE_DEBUG: (e6ae78f114334ad2): Workflow config is missing a default resource spec. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f4164: 2016-12-12T09:48:47.332Z: JOB_MESSAGE_DETAILED: (e6ae78f1143348bc): Adding StepResource setup and teardown to workflow graph. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f4199: 2016-12-12T09:48:47.385Z: JOB_MESSAGE_DEBUG: (9ebaf28efec7b630): Adding workflow start and stop steps. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f41cb: 2016-12-12T09:48:47.435Z: JOB_MESSAGE_DEBUG: (fcaab63844a32fde): Assigning stage ids. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f4248: 2016-12-12T09:48:47.560Z: JOB_MESSAGE_DEBUG: (8892d10c9f2d1ec9): Executing wait step start2 root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f4268: 2016-12-12T09:48:47.592Z: JOB_MESSAGE_DEBUG: (8c3ae74ad3611760): Executing operation side root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f426d: 2016-12-12T09:48:47.597Z: JOB_MESSAGE_DEBUG: (e6ae78f114334385): Executing operation start root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f4280: 2016-12-12T09:48:47.616Z: JOB_MESSAGE_DEBUG: (217a7ee53a0bf5de): Value "start.out" materialized. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f4299: 2016-12-12T09:48:47.641Z: JOB_MESSAGE_BASIC: S01: (8c3ae74ad3611da6): Executing operation ViewAsSingleton(side.None)/CreatePCollectionView root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f42aa: 2016-12-12T09:48:47.658Z: JOB_MESSAGE_DEBUG: (12cfebd09c9b1a3a): Value "ViewAsSingleton(side.None)/CreatePCollectionView.out" materialized. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f42be: 2016-12-12T09:48:47.678Z: JOB_MESSAGE_BASIC: S02: (b5d60b6ce678713d): Executing operation compute root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f438d: 2016-12-12T09:48:47.885Z: JOB_MESSAGE_DEBUG: (926e79506bf14f72): Starting worker pool setup. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f26f4392: 2016-12-12T09:48:47.890Z: JOB_MESSAGE_BASIC: (926e79506bf140ac): Starting 1 workers... root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f270e39f: 2016-12-12T09:50:34.399Z: JOB_MESSAGE_DETAILED: (697f31173dc3420): Workers have started successfully. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f271a817: 2016-12-12T09:51:24.695Z: JOB_MESSAGE_ERROR: (dc021ed2ee92b84d): Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/batchworker.py", line 514, in do_work work_executor.execute() File "dataflow_worker/executor.py", line 892, in dataflow_worker.executor.MapTaskExecutor.execute (dataflow_worker/executor.c:24008) op.start() File "dataflow_worker/executor.py", line 456, in dataflow_worker.executor.DoOperation.start (dataflow_worker/executor.c:13870) def start(self): File "dataflow_worker/executor.py", line 483, in dataflow_worker.executor.DoOperation.start (dataflow_worker/executor.c:13685) self.dofn_runner = common.DoFnRunner( File "apache_beam/runners/common.py", line 89, in apache_beam.runners.common.DoFnRunner.__init__ (apache_beam/runners/common.c:3469) args, kwargs, [side_input[global_window] File "/usr/local/lib/python2.7/dist-packages/apache_beam/transforms/sideinputs.py", line 192, in __getitem__ _FilteringIterable(self._iterable, target_window), self._view_options) File "/usr/local/lib/python2.7/dist-packages/apache_beam/pvalue.py", line 279, in _from_runtime_iterable 'PCollection with more than one element accessed as ' ValueError: PCollection with more than one element accessed as a singleton view. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f271a880: 2016-12-12T09:51:24.800Z: JOB_MESSAGE_ERROR: (dc021ed2ee92b31d): Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/batchworker.py", line 514, in do_work work_executor.execute() File "dataflow_worker/executor.py", line 892, in dataflow_worker.executor.MapTaskExecutor.execute (dataflow_worker/executor.c:24008) op.start() File "dataflow_worker/executor.py", line 456, in dataflow_worker.executor.DoOperation.start (dataflow_worker/executor.c:13870) def start(self): File "dataflow_worker/executor.py", line 483, in dataflow_worker.executor.DoOperation.start (dataflow_worker/executor.c:13685) self.dofn_runner = common.DoFnRunner( File "apache_beam/runners/common.py", line 89, in apache_beam.runners.common.DoFnRunner.__init__ (apache_beam/runners/common.c:3469) args, kwargs, [side_input[global_window] File "/usr/local/lib/python2.7/dist-packages/apache_beam/transforms/sideinputs.py", line 192, in __getitem__ _FilteringIterable(self._iterable, target_window), self._view_options) File "/usr/local/lib/python2.7/dist-packages/apache_beam/pvalue.py", line 279, in _from_runtime_iterable 'PCollection with more than one element accessed as ' ValueError: PCollection with more than one element accessed as a singleton view. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f271a8e7: 2016-12-12T09:51:24.903Z: JOB_MESSAGE_ERROR: (dc021ed2ee92bded): Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/batchworker.py", line 514, in do_work work_executor.execute() File "dataflow_worker/executor.py", line 892, in dataflow_worker.executor.MapTaskExecutor.execute (dataflow_worker/executor.c:24008) op.start() File "dataflow_worker/executor.py", line 456, in dataflow_worker.executor.DoOperation.start (dataflow_worker/executor.c:13870) def start(self): File "dataflow_worker/executor.py", line 483, in dataflow_worker.executor.DoOperation.start (dataflow_worker/executor.c:13685) self.dofn_runner = common.DoFnRunner( File "apache_beam/runners/common.py", line 89, in apache_beam.runners.common.DoFnRunner.__init__ (apache_beam/runners/common.c:3469) args, kwargs, [side_input[global_window] File "/usr/local/lib/python2.7/dist-packages/apache_beam/transforms/sideinputs.py", line 192, in __getitem__ _FilteringIterable(self._iterable, target_window), self._view_options) File "/usr/local/lib/python2.7/dist-packages/apache_beam/pvalue.py", line 279, in _from_runtime_iterable 'PCollection with more than one element accessed as ' ValueError: PCollection with more than one element accessed as a singleton view. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f271a94b: 2016-12-12T09:51:25.003Z: JOB_MESSAGE_ERROR: (dc021ed2ee92b8bd): Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/batchworker.py", line 514, in do_work work_executor.execute() File "dataflow_worker/executor.py", line 892, in dataflow_worker.executor.MapTaskExecutor.execute (dataflow_worker/executor.c:24008) op.start() File "dataflow_worker/executor.py", line 456, in dataflow_worker.executor.DoOperation.start (dataflow_worker/executor.c:13870) def start(self): File "dataflow_worker/executor.py", line 483, in dataflow_worker.executor.DoOperation.start (dataflow_worker/executor.c:13685) self.dofn_runner = common.DoFnRunner( File "apache_beam/runners/common.py", line 89, in apache_beam.runners.common.DoFnRunner.__init__ (apache_beam/runners/common.c:3469) args, kwargs, [side_input[global_window] File "/usr/local/lib/python2.7/dist-packages/apache_beam/transforms/sideinputs.py", line 192, in __getitem__ _FilteringIterable(self._iterable, target_window), self._view_options) File "/usr/local/lib/python2.7/dist-packages/apache_beam/pvalue.py", line 279, in _from_runtime_iterable 'PCollection with more than one element accessed as ' ValueError: PCollection with more than one element accessed as a singleton view. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f271a9be: 2016-12-12T09:51:25.118Z: JOB_MESSAGE_ERROR: (dc021ed2ee92b38d): Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/dataflow_worker/batchworker.py", line 514, in do_work work_executor.execute() File "dataflow_worker/executor.py", line 892, in dataflow_worker.executor.MapTaskExecutor.execute (dataflow_worker/executor.c:24008) op.start() File "dataflow_worker/executor.py", line 456, in dataflow_worker.executor.DoOperation.start (dataflow_worker/executor.c:13870) def start(self): File "dataflow_worker/executor.py", line 483, in dataflow_worker.executor.DoOperation.start (dataflow_worker/executor.c:13685) self.dofn_runner = common.DoFnRunner( File "apache_beam/runners/common.py", line 89, in apache_beam.runners.common.DoFnRunner.__init__ (apache_beam/runners/common.c:3469) args, kwargs, [side_input[global_window] File "/usr/local/lib/python2.7/dist-packages/apache_beam/transforms/sideinputs.py", line 192, in __getitem__ _FilteringIterable(self._iterable, target_window), self._view_options) File "/usr/local/lib/python2.7/dist-packages/apache_beam/pvalue.py", line 279, in _from_runtime_iterable 'PCollection with more than one element accessed as ' ValueError: PCollection with more than one element accessed as a singleton view. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f271a9e6: 2016-12-12T09:51:25.158Z: JOB_MESSAGE_DEBUG: (9ebaf28efec7bc97): Executing failure step failure1 root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f271a9e8: 2016-12-12T09:51:25.160Z: JOB_MESSAGE_ERROR: (9ebaf28efec7b631): Workflow failed. Causes: (b5d60b6ce6787a5a): S02:compute failed. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f271aa1c: 2016-12-12T09:51:25.212Z: JOB_MESSAGE_DETAILED: (cb00bb471965d908): Cleaning up. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f271aaa3: 2016-12-12T09:51:25.347Z: JOB_MESSAGE_DEBUG: (cb00bb471965db4d): Starting worker pool teardown. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f271aaa6: 2016-12-12T09:51:25.350Z: JOB_MESSAGE_BASIC: (cb00bb471965d77b): Stopping worker pool... root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f2730a3d: 2016-12-12T09:52:55.357Z: JOB_MESSAGE_BASIC: (cb00bb471965d9c0): Worker pool stopped. root: INFO: 2016-12-12_01_48_45-8789293249756495297_00000158f2730a54: 2016-12-12T09:52:55.380Z: JOB_MESSAGE_DEBUG: (cb00bb471965d833): Tearing down pending resources... root: INFO: Job 2016-12-12_01_48_45-8789293249756495297 is in state JOB_STATE_FAILED --------------------- >> end captured logging << --------------------- ---------------------------------------------------------------------- Ran 21 tests in 5154.856s FAILED (errors=1) Build step 'Execute shell' marked build as failure