See <https://builds.apache.org/job/beam_PostCommit_Python_Verify/853/>
------------------------------------------ [...truncated 2731 lines...] { "@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-09_14_25_27-17101999068727174841' projectId: u'apache-beam-testing' steps: [] tempFiles: [] type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)> root: INFO: Created job with id: [2016-12-09_14_25_27-17101999068727174841] root: INFO: To access the Dataflow monitoring console, please navigate to https://console.developers.google.com/project/apache-beam-testing/dataflow/job/2016-12-09_14_25_27-17101999068727174841 root: INFO: Job 2016-12-09_14_25_27-17101999068727174841 is in state JOB_STATE_RUNNING root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f2ec: 2016-12-09T22:25:28.812Z: JOB_MESSAGE_DETAILED: (7a823fb076f1ba95): Checking required Cloud APIs are enabled. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f47b: 2016-12-09T22:25:29.211Z: JOB_MESSAGE_DETAILED: (7a823fb076f1b71a): Expanding GroupByKey operations into optimizable parts. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f47d: 2016-12-09T22:25:29.213Z: JOB_MESSAGE_DETAILED: (7a823fb076f1b654): Lifting ValueCombiningMappingFns into MergeBucketsMappingFns root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f47f: 2016-12-09T22:25:29.215Z: JOB_MESSAGE_DETAILED: (7a823fb076f1b58e): Annotating graph with Autotuner information. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f488: 2016-12-09T22:25:29.224Z: JOB_MESSAGE_DETAILED: (7a823fb076f1b33c): Fusing adjacent ParDo, Read, Write, and Flatten operations root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f497: 2016-12-09T22:25:29.239Z: JOB_MESSAGE_DEBUG: (7a823fb076f1bdd2): Workflow config is missing a default resource spec. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f499: 2016-12-09T22:25:29.241Z: JOB_MESSAGE_DETAILED: (7a823fb076f1bd0c): Adding StepResource setup and teardown to workflow graph. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f4a7: 2016-12-09T22:25:29.255Z: JOB_MESSAGE_DEBUG: (1a2e004acf7c3a30): Adding workflow start and stop steps. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f4c3: 2016-12-09T22:25:29.283Z: JOB_MESSAGE_DEBUG: (f940362f2a0e498a): Assigning stage ids. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f592: 2016-12-09T22:25:29.490Z: JOB_MESSAGE_DEBUG: (8b59803cff860619): Executing wait step start2 root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f59b: 2016-12-09T22:25:29.499Z: JOB_MESSAGE_DEBUG: (a79a8a608ad787ec): Executing operation side root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f5a8: 2016-12-09T22:25:29.512Z: JOB_MESSAGE_DEBUG: (f940362f2a0e4fbe): Value "side.out" materialized. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f5c4: 2016-12-09T22:25:29.540Z: JOB_MESSAGE_DEBUG: (923124431eaf064a): Executing operation start root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f5c6: 2016-12-09T22:25:29.542Z: JOB_MESSAGE_BASIC: S01: (cbeee63e7ee29139): Executing operation ViewAsSingleton(side.None)/CreatePCollectionView root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f5cf: 2016-12-09T22:25:29.551Z: JOB_MESSAGE_DEBUG: (7a823fb076f1bb80): Value "start.out" materialized. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f5d1: 2016-12-09T22:25:29.553Z: JOB_MESSAGE_DEBUG: (8b59803cff860882): Value "ViewAsSingleton(side.None)/CreatePCollectionView.out" materialized. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f5da: 2016-12-09T22:25:29.562Z: JOB_MESSAGE_BASIC: S02: (92ab813ed4b56768): Executing operation compute root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f6a5: 2016-12-09T22:25:29.765Z: JOB_MESSAGE_DEBUG: (12f0f53be03e7ccf): Starting worker pool setup. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b0f6a7: 2016-12-09T22:25:29.767Z: JOB_MESSAGE_BASIC: (12f0f53be03e7c8d): Starting 1 workers... root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b27e34: 2016-12-09T22:27:10.004Z: JOB_MESSAGE_DETAILED: (14d5e56b69d608e): Workers have started successfully. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b3308a: 2016-12-09T22:27:55.658Z: JOB_MESSAGE_ERROR: (99aeafa7a8dffe87): 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-09_14_25_27-17101999068727174841_00000158e5b330e7: 2016-12-09T22:27:55.751Z: JOB_MESSAGE_ERROR: (99aeafa7a8dff617): 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-09_14_25_27-17101999068727174841_00000158e5b3314f: 2016-12-09T22:27:55.855Z: JOB_MESSAGE_ERROR: (99aeafa7a8dffda7): 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-09_14_25_27-17101999068727174841_00000158e5b331b0: 2016-12-09T22:27:55.952Z: JOB_MESSAGE_ERROR: (99aeafa7a8dff537): 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-09_14_25_27-17101999068727174841_00000158e5b33205: 2016-12-09T22:27:56.037Z: JOB_MESSAGE_ERROR: (99aeafa7a8dffcc7): 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-09_14_25_27-17101999068727174841_00000158e5b3322b: 2016-12-09T22:27:56.075Z: JOB_MESSAGE_DEBUG: (a79a8a608ad78289): Executing failure step failure1 root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b3322e: 2016-12-09T22:27:56.078Z: JOB_MESSAGE_ERROR: (a79a8a608ad780ff): Workflow failed. Causes: (92ab813ed4b56a47): S02:compute failed. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b3352c: 2016-12-09T22:27:56.844Z: JOB_MESSAGE_DETAILED: (8b59803cff860500): Cleaning up. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b33533: 2016-12-09T22:27:56.851Z: JOB_MESSAGE_DEBUG: (8b59803cff86033f): Starting worker pool teardown. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b33535: 2016-12-09T22:27:56.853Z: JOB_MESSAGE_BASIC: (8b59803cff860769): Stopping worker pool... root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b494cc: 2016-12-09T22:29:26.860Z: JOB_MESSAGE_BASIC: (8b59803cff8605a8): Worker pool stopped. root: INFO: 2016-12-09_14_25_27-17101999068727174841_00000158e5b494e0: 2016-12-09T22:29:26.880Z: JOB_MESSAGE_DEBUG: (8b59803cff860811): Tearing down pending resources... root: INFO: Job 2016-12-09_14_25_27-17101999068727174841 is in state JOB_STATE_FAILED --------------------- >> end captured logging << --------------------- ---------------------------------------------------------------------- Ran 21 tests in 5361.453s FAILED (errors=1) Build step 'Execute shell' marked build as failure