See <https://builds.apache.org/job/beam_PostCommit_Python_Verify/883/>
------------------------------------------ [...truncated 2878 lines...] "component_encodings": [] } ], "is_pair_like": true } ], "is_wrapper": true }, "output_name": "out", "user_name": "assert_that/singleton.out" } ], "user_name": "assert_that/singleton" } }, { "kind": "ParallelDo", "name": "s6", "properties": { "display_data": [ { "key": "fn", "label": "Transform Function", "namespace": "apache_beam.transforms.core.WindowInto", "shortValue": "WindowIntoFn", "type": "STRING", "value": "apache_beam.transforms.core.WindowIntoFn" } ], "non_parallel_inputs": {}, "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": "assert_that/WindowInto.out" } ], "parallel_input": { "@type": "OutputReference", "output_name": "out", "step_name": "s4" }, "serialized_fn": "<string of 432 bytes>", "user_name": "assert_that/WindowInto" } }, { "kind": "CollectionToSingleton", "name": "s7", "properties": { "display_data": [], "output_info": [ { "encoding": { "@type": "WindowedValueCoder$<string of 408 bytes>", "component_encodings": [ { "@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": "assert_that/ViewAsList(assert_that|WindowInto.None)/CreatePCollectionView.out" } ], "parallel_input": { "@type": "OutputReference", "output_name": "out", "step_name": "s6" }, "user_name": "assert_that/ViewAsList(assert_that|WindowInto.None)/CreatePCollectionView" } }, { "kind": "ParallelDo", "name": "s8", "properties": { "display_data": [ { "key": "fn", "label": "Transform Function", "namespace": "apache_beam.transforms.core.CallableWrapperDoFn", "type": "STRING", "value": "match" }, { "key": "fn", "label": "Transform Function", "namespace": "apache_beam.transforms.core.ParDo", "shortValue": "CallableWrapperDoFn", "type": "STRING", "value": "apache_beam.transforms.core.CallableWrapperDoFn" } ], "non_parallel_inputs": { "s7": { "@type": "OutputReference", "output_name": "out", "step_name": "s7" } }, "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": "assert_that/Map(match).out" } ], "parallel_input": { "@type": "OutputReference", "output_name": "out", "step_name": "s5" }, "serialized_fn": "<string of 1540 bytes>", "user_name": "assert_that/Map(match)" } } ], "type": "JOB_TYPE_BATCH" } root: INFO: Create job: <Job id: u'2016-12-16_07_02_26-15455366080453718208' projectId: u'apache-beam-testing' steps: [] tempFiles: [] type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)> root: INFO: Created job with id: [2016-12-16_07_02_26-15455366080453718208] root: INFO: To access the Dataflow monitoring console, please navigate to https://console.developers.google.com/project/apache-beam-testing/dataflow/job/2016-12-16_07_02_26-15455366080453718208 root: INFO: Job 2016-12-16_07_02_26-15455366080453718208 is in state JOB_STATE_RUNNING root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827df2f: 2016-12-16T15:02:27.887Z: JOB_MESSAGE_DETAILED: (3fff68d9d1537f9f): Checking required Cloud APIs are enabled. root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e0a1: 2016-12-16T15:02:28.257Z: JOB_MESSAGE_DETAILED: (3fff68d9d1537cd4): Expanding GroupByKey operations into optimizable parts. root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e0a3: 2016-12-16T15:02:28.259Z: JOB_MESSAGE_DETAILED: (3fff68d9d1537a6e): Lifting ValueCombiningMappingFns into MergeBucketsMappingFns root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e0a6: 2016-12-16T15:02:28.262Z: JOB_MESSAGE_DETAILED: (3fff68d9d1537808): Annotating graph with Autotuner information. root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e0ae: 2016-12-16T15:02:28.270Z: JOB_MESSAGE_DETAILED: (3fff68d9d15377a3): Fusing adjacent ParDo, Read, Write, and Flatten operations root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e0b2: 2016-12-16T15:02:28.274Z: JOB_MESSAGE_DETAILED: (3fff68d9d15372d7): Fusing consumer assert_that/WindowInto into test root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e0ca: 2016-12-16T15:02:28.298Z: JOB_MESSAGE_DEBUG: (3fff68d9d153760f): Workflow config is missing a default resource spec. root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e0cc: 2016-12-16T15:02:28.300Z: JOB_MESSAGE_DETAILED: (3fff68d9d15373a9): Adding StepResource setup and teardown to workflow graph. root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e0d9: 2016-12-16T15:02:28.313Z: JOB_MESSAGE_DEBUG: (1e2b5fb0d18b4928): Adding workflow start and stop steps. root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e0e2: 2016-12-16T15:02:28.322Z: JOB_MESSAGE_DEBUG: (3fff68d9d1537edd): Assigning stage ids. root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e12d: 2016-12-16T15:02:28.397Z: JOB_MESSAGE_DEBUG: (1e2b5fb0d18b4fe1): Executing wait step start2 root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e137: 2016-12-16T15:02:28.407Z: JOB_MESSAGE_DEBUG: (9ac6a472e608e5b6): Executing operation assert_that/singleton root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e139: 2016-12-16T15:02:28.409Z: JOB_MESSAGE_DEBUG: (70da03a0a4594668): Executing operation main input root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e15c: 2016-12-16T15:02:28.444Z: JOB_MESSAGE_DEBUG: (9ac6a472e608e464): Value "side kvs.out" materialized. root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e15f: 2016-12-16T15:02:28.447Z: JOB_MESSAGE_DEBUG: (9442fc255c8adf52): Value "main input.out" materialized. root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e167: 2016-12-16T15:02:28.455Z: JOB_MESSAGE_BASIC: S01: (c4d2f5297c00e209): Executing operation ViewAsDict(side kvs.None)/CreatePCollectionView root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e171: 2016-12-16T15:02:28.465Z: JOB_MESSAGE_DEBUG: (c4d2f5297c00e981): Value "ViewAsDict(side kvs.None)/CreatePCollectionView.out" materialized. root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e179: 2016-12-16T15:02:28.473Z: JOB_MESSAGE_BASIC: S02: (c4d2f5297c00eb1b): Executing operation test+assert_that/WindowInto root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e244: 2016-12-16T15:02:28.676Z: JOB_MESSAGE_DEBUG: (5047d7fe82da6186): Starting worker pool setup. root: INFO: 2016-12-16_07_02_26-15455366080453718208_000001590827e247: 2016-12-16T15:02:28.679Z: JOB_MESSAGE_BASIC: (5047d7fe82da6198): Starting 1 workers... root: INFO: 2016-12-16_07_02_26-15455366080453718208_0000015908296cf0: 2016-12-16T15:04:09.712Z: JOB_MESSAGE_DETAILED: (40c75d81aa5deff4): Workers have started successfully. root: INFO: 2016-12-16_07_02_26-15455366080453718208_00000159082a2e26: 2016-12-16T15:04:59.174Z: JOB_MESSAGE_DEBUG: (32e94c718fac5435): Value "assert_that/WindowInto.out" materialized. root: INFO: 2016-12-16_07_02_26-15455366080453718208_00000159082a2e2f: 2016-12-16T15:04:59.183Z: JOB_MESSAGE_BASIC: S03: (70da03a0a45942c7): Executing operation assert_that/ViewAsList(assert_that|WindowInto.None)/CreatePCollectionView root: INFO: 2016-12-16_07_02_26-15455366080453718208_00000159082a2ee8: 2016-12-16T15:04:59.368Z: JOB_MESSAGE_DEBUG: (c4d2f5297c00e31d): Value "assert_that/ViewAsList(assert_that|WindowInto.None)/CreatePCollectionView.out" materialized. root: INFO: 2016-12-16_07_02_26-15455366080453718208_00000159082a2ef2: 2016-12-16T15:04:59.378Z: JOB_MESSAGE_BASIC: S04: (c4d2f5297c00e4b7): Executing operation assert_that/Map(match) root: INFO: 2016-12-16_07_02_26-15455366080453718208_00000159082a305f: 2016-12-16T15:04:59.743Z: JOB_MESSAGE_DEBUG: (12268240252c86b8): Executing failure step failure1 root: INFO: 2016-12-16_07_02_26-15455366080453718208_00000159082a3061: 2016-12-16T15:04:59.745Z: JOB_MESSAGE_ERROR: (12268240252c8476): Workflow failed. Causes: (c4d2f5297c00e3a7): S04:assert_that/Map(match) failed., Internal Issue (18a16d05d3c596b2): 64572131:20100, (18a16d05d3c5981f): Unable to expand file pattern gs://temp-storage-for-end-to-end-tests/staging-validatesrunner-test/py-validatesrunner-1481900543.1481900545.721409/tmp-c4d2f5297c00ecb5@*.avro root: INFO: 2016-12-16_07_02_26-15455366080453718208_00000159082a3094: 2016-12-16T15:04:59.796Z: JOB_MESSAGE_DETAILED: (9442fc255c8ad468): Cleaning up. root: INFO: 2016-12-16_07_02_26-15455366080453718208_00000159082a311d: 2016-12-16T15:04:59.933Z: JOB_MESSAGE_DEBUG: (9442fc255c8ad6f3): Starting worker pool teardown. root: INFO: 2016-12-16_07_02_26-15455366080453718208_00000159082a311f: 2016-12-16T15:04:59.935Z: JOB_MESSAGE_BASIC: (9442fc255c8ad8a5): Stopping worker pool... root: INFO: 2016-12-16_07_02_26-15455366080453718208_00000159082c4970: 2016-12-16T15:07:17.232Z: JOB_MESSAGE_DETAILED: (9cdff9f8ed5655bd): Cleaning up. root: INFO: 2016-12-16_07_02_26-15455366080453718208_00000159082c5d04: 2016-12-16T15:07:22.244Z: JOB_MESSAGE_BASIC: (9cdff9f8ed565091): Worker pool stopped. root: INFO: 2016-12-16_07_02_26-15455366080453718208_00000159082c5d15: 2016-12-16T15:07:22.261Z: JOB_MESSAGE_DEBUG: (9cdff9f8ed565e1a): Tearing down pending resources... root: INFO: Job 2016-12-16_07_02_26-15455366080453718208 is in state JOB_STATE_FAILED --------------------- >> end captured logging << --------------------- ---------------------------------------------------------------------- Ran 20 tests in 4887.862s FAILED (errors=1) Build step 'Execute shell' marked build as failure