See <https://builds.apache.org/job/beam_PostCommit_Python_Verify/1509/display/redirect>
------------------------------------------ [...truncated 453.30 KB...] "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": "FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/", "component_encodings": [ { "@type": "FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/", "component_encodings": [] }, { "@type": "FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/", "component_encodings": [] } ], "is_pair_like": true }, { "@type": "kind:global_window" } ], "is_wrapper": true }, "output_name": "out", "user_name": "write/Write/WriteImpl/Extract.out" } ], "parallel_input": { "@type": "OutputReference", "output_name": "out", "step_name": "s13" }, "serialized_fn": "<string of 744 bytes>", "user_name": "write/Write/WriteImpl/Extract" } }, { "kind": "CollectionToSingleton", "name": "s15", "properties": { "display_data": [], "output_info": [ { "encoding": { "@type": "kind:windowed_value", "component_encodings": [ { "@type": "kind:windowed_value", "component_encodings": [ { "@type": "FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/", "component_encodings": [ { "@type": "FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/", "component_encodings": [] }, { "@type": "FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/", "component_encodings": [] } ], "is_pair_like": true }, { "@type": "kind:global_window" } ], "is_wrapper": true } ] }, "output_name": "out", "user_name": "write/Write/WriteImpl/ViewAsIterable(write|Write|WriteImpl|Extract.None)/CreatePCollectionView.out" } ], "parallel_input": { "@type": "OutputReference", "output_name": "out", "step_name": "s14" }, "user_name": "write/Write/WriteImpl/ViewAsIterable(write|Write|WriteImpl|Extract.None)/CreatePCollectionView" } }, { "kind": "ParallelDo", "name": "s16", "properties": { "display_data": [ { "key": "fn", "label": "Transform Function", "namespace": "apache_beam.transforms.core.CallableWrapperDoFn", "type": "STRING", "value": "_finalize_write" }, { "key": "fn", "label": "Transform Function", "namespace": "apache_beam.transforms.core.ParDo", "shortValue": "CallableWrapperDoFn", "type": "STRING", "value": "apache_beam.transforms.core.CallableWrapperDoFn" } ], "non_parallel_inputs": { "s15": { "@type": "OutputReference", "output_name": "out", "step_name": "s15" }, "s9": { "@type": "OutputReference", "output_name": "out", "step_name": "s9" } }, "output_info": [ { "encoding": { "@type": "kind:windowed_value", "component_encodings": [ { "@type": "FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/", "component_encodings": [ { "@type": "FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/", "component_encodings": [] }, { "@type": "FastPrimitivesCoder$eNprYEpOLEhMzkiNT0pNzNVLzk9JLSqGUlxuicUlAUWZuZklmWWpxc4gQa5CBs3GQsbaQqZQ/vi0xJycpMTk7Hiw+kJmPEYFZCZn56RCjWABGsFaW8iWVJykBwDlGS3/", "component_encodings": [] } ], "is_pair_like": true }, { "@type": "kind:global_window" } ], "is_wrapper": true }, "output_name": "out", "user_name": "write/Write/WriteImpl/finalize_write.out" } ], "parallel_input": { "@type": "OutputReference", "output_name": "out", "step_name": "s7" }, "serialized_fn": "<string of 996 bytes>", "user_name": "write/Write/WriteImpl/finalize_write" } } ], "type": "JOB_TYPE_BATCH" } root: INFO: Create job: <Job createTime: u'2017-03-15T02:15:08.860195Z' currentStateTime: u'1970-01-01T00:00:00Z' id: u'2017-03-14_19_15_08-14830685498213987975' name: u'py-wordcount-1489539502' projectId: u'apache-beam-testing' steps: [] tempFiles: [] type: TypeValueValuesEnum(JOB_TYPE_BATCH, 1)> root: INFO: Created job with id: [2017-03-14_19_15_08-14830685498213987975] root: INFO: To access the Dataflow monitoring console, please navigate to https://console.developers.google.com/project/apache-beam-testing/dataflow/job/2017-03-14_19_15_08-14830685498213987975 root: INFO: Job 2017-03-14_19_15_08-14830685498213987975 is in state JOB_STATE_RUNNING root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf597a: 2017-03-15T02:15:08.410Z: JOB_MESSAGE_WARNING: (cdd12de26094f96b): Setting the number of workers (1) disables autoscaling for this job. If you are trying to cap autoscaling, consider only setting max_num_workers. If you want to disable autoscaling altogether, the documented way is to explicitly use autoscalingAlgorithm=NONE. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf5ec0: 2017-03-15T02:15:09.760Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15669): Checking required Cloud APIs are enabled. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf622d: 2017-03-15T02:15:10.637Z: JOB_MESSAGE_DEBUG: (3a5c80e9af155bb): Combiner lifting skipped for step write/Write/WriteImpl/GroupByKey: GroupByKey not followed by a combiner. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf622f: 2017-03-15T02:15:10.639Z: JOB_MESSAGE_DEBUG: (3a5c80e9af15265): Combiner lifting skipped for step group: GroupByKey not followed by a combiner. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf6232: 2017-03-15T02:15:10.642Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15f0f): Expanding GroupByKey operations into optimizable parts. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf6235: 2017-03-15T02:15:10.645Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15bb9): Lifting ValueCombiningMappingFns into MergeBucketsMappingFns root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf623b: 2017-03-15T02:15:10.651Z: JOB_MESSAGE_DETAILED: (3a5c80e9af151b7): Annotating graph with Autotuner information. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf6245: 2017-03-15T02:15:10.661Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15160): Fusing adjacent ParDo, Read, Write, and Flatten operations root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf6248: 2017-03-15T02:15:10.664Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15e0a): Fusing consumer split into read/Read root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf624a: 2017-03-15T02:15:10.666Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15ab4): Fusing consumer group/Reify into pair_with_one root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf624c: 2017-03-15T02:15:10.668Z: JOB_MESSAGE_DETAILED: (3a5c80e9af1575e): Fusing consumer format into count root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf624e: 2017-03-15T02:15:10.670Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15408): Fusing consumer write/Write/WriteImpl/GroupByKey/GroupByWindow into write/Write/WriteImpl/GroupByKey/Read root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf6250: 2017-03-15T02:15:10.672Z: JOB_MESSAGE_DETAILED: (3a5c80e9af150b2): Fusing consumer write/Write/WriteImpl/GroupByKey/Write into write/Write/WriteImpl/GroupByKey/Reify root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf6255: 2017-03-15T02:15:10.677Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15a06): Fusing consumer write/Write/WriteImpl/Extract into write/Write/WriteImpl/GroupByKey/GroupByWindow root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf6257: 2017-03-15T02:15:10.679Z: JOB_MESSAGE_DETAILED: (3a5c80e9af156b0): Fusing consumer count into group/GroupByWindow root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf6259: 2017-03-15T02:15:10.681Z: JOB_MESSAGE_DETAILED: (3a5c80e9af1535a): Fusing consumer write/Write/WriteImpl/WindowInto(WindowIntoFn) into write/Write/WriteImpl/Pair root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf625b: 2017-03-15T02:15:10.683Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15004): Fusing consumer write/Write/WriteImpl/GroupByKey/Reify into write/Write/WriteImpl/WindowInto(WindowIntoFn) root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf625d: 2017-03-15T02:15:10.685Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15cae): Fusing consumer write/Write/WriteImpl/Pair into write/Write/WriteImpl/WriteBundles root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf625f: 2017-03-15T02:15:10.687Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15958): Fusing consumer pair_with_one into split root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf6262: 2017-03-15T02:15:10.690Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15602): Fusing consumer group/GroupByWindow into group/Read root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf6264: 2017-03-15T02:15:10.692Z: JOB_MESSAGE_DETAILED: (3a5c80e9af152ac): Fusing consumer write/Write/WriteImpl/WriteBundles into format root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf6266: 2017-03-15T02:15:10.694Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15f56): Fusing consumer group/Write into group/Reify root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf62b4: 2017-03-15T02:15:10.772Z: JOB_MESSAGE_DEBUG: (3a5c80e9af15496): Workflow config is missing a default resource spec. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf62b7: 2017-03-15T02:15:10.775Z: JOB_MESSAGE_DETAILED: (3a5c80e9af15140): Adding StepResource setup and teardown to workflow graph. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf62b9: 2017-03-15T02:15:10.777Z: JOB_MESSAGE_DEBUG: (3a5c80e9af15dea): Adding workflow start and stop steps. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf62bc: 2017-03-15T02:15:10.780Z: JOB_MESSAGE_DEBUG: (3a5c80e9af15a94): Assigning stage ids. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf62df: 2017-03-15T02:15:10.815Z: JOB_MESSAGE_DEBUG: (4079fcc838ff349d): Executing wait step start25 root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf62e6: 2017-03-15T02:15:10.822Z: JOB_MESSAGE_BASIC: S01: (4079fcc838ff3603): Executing operation group/Create root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf62e8: 2017-03-15T02:15:10.824Z: JOB_MESSAGE_DEBUG: (b74494551d26a9c7): Executing operation write/Write/WriteImpl/DoOnce root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf62f2: 2017-03-15T02:15:10.834Z: JOB_MESSAGE_DEBUG: (b74494551d26a378): Value "write/Write/WriteImpl/DoOnce.out" materialized. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf62fb: 2017-03-15T02:15:10.843Z: JOB_MESSAGE_BASIC: S04: (b74494551d26a7b0): Executing operation write/Write/WriteImpl/InitializeWrite root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf63b2: 2017-03-15T02:15:11.026Z: JOB_MESSAGE_DEBUG: (7b5395c83800f5fc): Starting worker pool setup. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf63b4: 2017-03-15T02:15:11.028Z: JOB_MESSAGE_BASIC: (7b5395c83800f1aa): Starting 1 workers... root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf63c0: 2017-03-15T02:15:11.040Z: JOB_MESSAGE_DEBUG: (4079fcc838ff301c): Value "group/Session" materialized. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfbf63c8: 2017-03-15T02:15:11.048Z: JOB_MESSAGE_BASIC: S02: (4079fcc838ff3ca4): Executing operation read/Read+split+pair_with_one+group/Reify+group/Write root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc04f19: 2017-03-15T02:16:11.289Z: JOB_MESSAGE_DETAILED: (aad9f80da636a35c): Workers have started successfully. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc26e84: 2017-03-15T02:18:30.404Z: JOB_MESSAGE_DEBUG: (b74494551d26a74f): Value "write/Write/WriteImpl/InitializeWrite.out" materialized. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc26e8f: 2017-03-15T02:18:30.415Z: JOB_MESSAGE_BASIC: S05: (b74494551d26a100): Executing operation write/Write/WriteImpl/ViewAsSingleton(write|Write|WriteImpl|InitializeWrite.None)/CreatePCollectionView root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc26ed6: 2017-03-15T02:18:30.486Z: JOB_MESSAGE_DEBUG: (b74494551d26a4cd): Value "write/Write/WriteImpl/ViewAsSingleton(write|Write|WriteImpl|InitializeWrite.None)/CreatePCollectionView.out" materialized. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc29119: 2017-03-15T02:18:39.257Z: JOB_MESSAGE_BASIC: S03: (4079fcc838ff3f70): Executing operation group/Close root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc2912b: 2017-03-15T02:18:39.275Z: JOB_MESSAGE_BASIC: S06: (4079fcc838ff3989): Executing operation write/Write/WriteImpl/GroupByKey/Create root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc291df: 2017-03-15T02:18:39.455Z: JOB_MESSAGE_DEBUG: (4079fcc838ff33a2): Value "write/Write/WriteImpl/GroupByKey/Session" materialized. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc291e7: 2017-03-15T02:18:39.463Z: JOB_MESSAGE_BASIC: S07: (4079fcc838ff3299): Executing operation group/Read+group/GroupByWindow+count+format+write/Write/WriteImpl/WriteBundles+write/Write/WriteImpl/Pair+write/Write/WriteImpl/WindowInto(WindowIntoFn)+write/Write/WriteImpl/GroupByKey/Reify+write/Write/WriteImpl/GroupByKey/Write root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc2b7a8: 2017-03-15T02:18:49.128Z: JOB_MESSAGE_BASIC: S08: (4079fcc838ff3d0f): Executing operation write/Write/WriteImpl/GroupByKey/Close root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc2b7c2: 2017-03-15T02:18:49.154Z: JOB_MESSAGE_BASIC: S09: (4079fcc838ff3997): Executing operation write/Write/WriteImpl/GroupByKey/Read+write/Write/WriteImpl/GroupByKey/GroupByWindow+write/Write/WriteImpl/Extract root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc2de82: 2017-03-15T02:18:59.074Z: JOB_MESSAGE_DEBUG: (4079fcc838ff3e26): Value "write/Write/WriteImpl/Extract.out" materialized. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc2de89: 2017-03-15T02:18:59.081Z: JOB_MESSAGE_BASIC: S10: (4079fcc838ff3d1d): Executing operation write/Write/WriteImpl/ViewAsIterable(write|Write|WriteImpl|Extract.None)/CreatePCollectionView root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc2ded0: 2017-03-15T02:18:59.152Z: JOB_MESSAGE_DEBUG: (4079fcc838ff3258): Value "write/Write/WriteImpl/ViewAsIterable(write|Write|WriteImpl|Extract.None)/CreatePCollectionView.out" materialized. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc2ded8: 2017-03-15T02:18:59.160Z: JOB_MESSAGE_BASIC: S11: (4079fcc838ff314f): Executing operation write/Write/WriteImpl/finalize_write root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc2ea57: 2017-03-15T02:19:02.103Z: JOB_MESSAGE_DEBUG: (4079fcc838ff3bc5): Executing success step success23 root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc2ea9c: 2017-03-15T02:19:02.172Z: JOB_MESSAGE_DETAILED: (3a5c80e9af1503b): Cleaning up. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc2eb14: 2017-03-15T02:19:02.292Z: JOB_MESSAGE_DEBUG: (3a5c80e9af15ce5): Starting worker pool teardown. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc2eb16: 2017-03-15T02:19:02.294Z: JOB_MESSAGE_BASIC: (3a5c80e9af1598f): Stopping worker pool... root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc3fc8d: 2017-03-15T02:20:12.301Z: JOB_MESSAGE_BASIC: (3a5c80e9af15c8e): Worker pool stopped. root: INFO: 2017-03-14_19_15_08-14830685498213987975_0000015acfc3fcaf: 2017-03-15T02:20:12.335Z: JOB_MESSAGE_DEBUG: (3a5c80e9af1528c): Tearing down pending resources... root: INFO: Job 2017-03-14_19_15_08-14830685498213987975 is in state JOB_STATE_DONE --------------------- >> end captured logging << --------------------- ---------------------------------------------------------------------- Ran 2 tests in 636.639s FAILED (errors=1) Build step 'Execute shell' marked build as failure