You are viewing a plain text version of this content. The canonical link for it is here.
Posted to commits@beam.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2018/03/14 19:56:27 UTC

Build failed in Jenkins: beam_PostCommit_Java_ValidatesRunner_Dataflow #5152

See <https://builds.apache.org/job/beam_PostCommit_Java_ValidatesRunner_Dataflow/5152/display/redirect>

------------------------------------------
[...truncated 24.94 MB...]
[INFO] 2018-03-14T19:52:14.852Z: (4d83e54bcc3cd40b): Fusing consumer View.AsSingleton/Combine.GloballyAsSingletonView/BatchViewOverrides.GroupByWindowHashAsKeyAndWindowAsSortKey/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Write into View.AsSingleton/Combine.GloballyAsSingletonView/BatchViewOverrides.GroupByWindowHashAsKeyAndWindowAsSortKey/ParDo(UseWindowHashAsKeyAndWindowAsSortKey)
[INFO] 2018-03-14T19:52:14.885Z: (4d83e54bcc3cdf91): Fusing consumer View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Combine.perKey(Singleton)/GroupByKey+View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Combine.perKey(Singleton)/Combine.GroupedValues/Partial into View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/WithKeys/AddKeys/Map
[INFO] 2018-03-14T19:52:14.913Z: (4d83e54bcc3cdb17): Fusing consumer View.AsSingleton/Combine.GloballyAsSingletonView/BatchViewOverrides.GroupByWindowHashAsKeyAndWindowAsSortKey/ParDo(UseWindowHashAsKeyAndWindowAsSortKey) into View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Values/Values/Map
[INFO] 2018-03-14T19:52:14.945Z: (4d83e54bcc3cd69d): Fusing consumer View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Values/Values/Map into View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Combine.perKey(Singleton)/Combine.GroupedValues/Extract
[INFO] 2018-03-14T19:52:15.290Z: (229bcc187051e219): Executing operation View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Combine.perKey(Singleton)/GroupByKey/Create
[INFO] 2018-03-14T19:52:15.372Z: (5bdd03f68f2325f3): Starting 1 workers in us-central1-f...
[INFO] 2018-03-14T19:52:15.485Z: (229bcc187051ea5f): Executing operation Create47/Read(CreateSource)+View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/WithKeys/AddKeys/Map+View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Combine.perKey(Singleton)/GroupByKey+View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Combine.perKey(Singleton)/Combine.GroupedValues/Partial+View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Combine.perKey(Singleton)/GroupByKey/Reify+View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Combine.perKey(Singleton)/GroupByKey/Write
[INFO] Job 2018-03-14_12_48_56-13254460280441587065 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_48_56-13254460280441587065. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-14T19:52:20.428Z: (69627fa7c145c450): Autoscaling: Raised the number of workers to 0 based on the rate of progress in the currently running step(s).
[INFO] Job 2018-03-14_12_48_42-11417587155086134930 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_48_42-11417587155086134930. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-14T19:52:20.205Z: (c3d4dd3e3a0fc029): Executing operation View.AsMap/GBKaSVForData/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Close
[INFO] 2018-03-14T19:52:20.264Z: (f626552758ddae96): Executing operation View.AsMap/GBKaSVForSize/Create
[INFO] 2018-03-14T19:52:20.292Z: (c3d4dd3e3a0fcc84): Executing operation View.AsMap/GBKaSVForKeys/Create
[INFO] 2018-03-14T19:52:20.444Z: (5b70d8d545db1169): Executing operation View.AsMap/GBKaSVForData/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Read+View.AsMap/ParMultiDo(ToIsmRecordForMapLike)+View.AsMap/GBKaSVForSize/Write+View.AsMap/GBKaSVForKeys/Write
[INFO] 2018-03-14T19:52:20.735Z: (112907c2b4c556d2): Executing operation PAssert$372/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-14T19:52:20.813Z: (112907c2b4c5593a): Executing operation PAssert$372/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-14T19:52:20.985Z: (112907c2b4c55f3e): Executing operation PAssert$372/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$372/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$372/GroupGlobally/GroupDummyAndContents/Reify+PAssert$372/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:52:21.017Z: (7fd06aa359ac68d7): Executing operation PAssert$372/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$372/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$372/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$372/GroupGlobally/RewindowActuals/Window.Assign+PAssert$372/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$372/GroupGlobally/GroupDummyAndContents/Reify+PAssert$372/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:52:20.409Z: (8daae814e19a9b8d): Executing operation PAssert$371/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-14T19:52:20.452Z: (ec6a02f40ce4d814): Executing operation PAssert$371/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-14T19:52:20.544Z: (ec6a02f40ce4d947): Executing operation PAssert$371/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$371/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$371/GroupGlobally/GroupDummyAndContents/Reify+PAssert$371/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:52:20.568Z: (8daae814e19a94fa): Executing operation PAssert$371/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$371/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$371/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$371/GroupGlobally/RewindowActuals/Window.Assign+PAssert$371/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$371/GroupGlobally/GroupDummyAndContents/Reify+PAssert$371/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:52:19.528Z: (b303deb30b1c2461): Executing operation PAssert$373/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-14T19:52:19.611Z: (b303deb30b1c24b9): Executing operation PAssert$373/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-14T19:52:19.731Z: (d7af3d700799510d): Executing operation PAssert$373/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$373/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$373/GroupGlobally/GroupDummyAndContents/Reify+PAssert$373/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:52:19.754Z: (b303deb30b1c2932): Executing operation PAssert$373/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$373/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$373/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$373/GroupGlobally/RewindowActuals/Window.Assign+PAssert$373/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$373/GroupGlobally/GroupDummyAndContents/Reify+PAssert$373/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:52:20.656Z: (66ca99ed107c51a4): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-14T19:52:20.688Z: (66ca99ed107c5472): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-14T19:52:20.724Z: (833461d9d6cc1513): Worker pool stopped.
[INFO] 2018-03-14T19:52:25.681Z: (c40122c3cc680a79): Autoscaling: Raised the number of workers to 0 based on the rate of progress in the currently running step(s).
[INFO] 2018-03-14T19:52:27.943Z: (ec6a02f40ce4def3): Executing operation PAssert$371/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-14T19:52:27.989Z: (8daae814e19a9f90): Executing operation PAssert$371/GroupGlobally/GroupDummyAndContents/Read+PAssert$371/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$371/GroupGlobally/Values/Values/Map+PAssert$371/GroupGlobally/ParDo(Concat)+PAssert$371/GetPane/Map+PAssert$371/RunChecks+PAssert$371/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-14T19:52:28.635Z: (b303deb30b1c21cc): Executing operation PAssert$373/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-14T19:52:28.695Z: (d7af3d700799575a): Executing operation PAssert$373/GroupGlobally/GroupDummyAndContents/Read+PAssert$373/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$373/GroupGlobally/Values/Values/Map+PAssert$373/GroupGlobally/ParDo(Concat)+PAssert$373/GetPane/Map+PAssert$373/RunChecks+PAssert$373/VerifyAssertions/ParDo(DefaultConclude)
[INFO] Job 2018-03-14_12_48_53-6727392848626752340 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_48_53-6727392848626752340. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-14T19:52:28.951Z: (a7ca354f8db52da): Workers have started successfully.
[INFO] 2018-03-14T19:52:29.282Z: (7aadbfd1aff5fe73): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-14T19:52:29.311Z: (7aadbfd1aff5f87d): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-14T19:52:29.137Z: (112907c2b4c552da): Executing operation PAssert$372/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-14T19:52:29.219Z: (112907c2b4c550f5): Executing operation PAssert$372/GroupGlobally/GroupDummyAndContents/Read+PAssert$372/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$372/GroupGlobally/Values/Values/Map+PAssert$372/GroupGlobally/ParDo(Concat)+PAssert$372/GetPane/Map+PAssert$372/RunChecks+PAssert$372/VerifyAssertions/ParDo(DefaultConclude)
[INFO] Job 2018-03-14_12_49_29-15518314699406103900 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_49_29-15518314699406103900. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-14T19:52:32.403Z: (70a7b71e8bbe095d): Cleaning up.
[INFO] 2018-03-14T19:52:32.154Z: (b055cec3d2c95ef0): Workers have started successfully.
[INFO] 2018-03-14T19:52:32.457Z: (70a7b71e8bbe0d6f): Stopping worker pool...
[INFO] 2018-03-14T19:52:32.266Z: (5b70d8d545db145b): Executing operation View.AsMap/GBKaSVForSize/Close
[INFO] 2018-03-14T19:52:32.301Z: (c3d4dd3e3a0fcd65): Executing operation View.AsMap/GBKaSVForKeys/Close
[INFO] 2018-03-14T19:52:32.351Z: (5b70d8d545db11f2): Executing operation View.AsMap/GBKaSVForSize/Read+View.AsMap/ParDo(ToIsmMetadataRecordForSize)
[INFO] 2018-03-14T19:52:32.386Z: (c3d4dd3e3a0fc53a): Executing operation View.AsMap/GBKaSVForKeys/Read+View.AsMap/ParDo(ToIsmMetadataRecordForKey)
[INFO] 2018-03-14T19:52:36.166Z: (665fb5a625a26edd): Cleaning up.
[INFO] 2018-03-14T19:52:36.234Z: (665fb5a625a261d7): Stopping worker pool...
[INFO] 2018-03-14T19:52:35.920Z: (957de5cb528e6ce8): Cleaning up.
[INFO] 2018-03-14T19:52:35.984Z: (957de5cb528e6f3e): Stopping worker pool...
[INFO] Job 2018-03-14_12_49_27-1407997269017886428 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_49_27-1407997269017886428. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-14T19:52:43.230Z: (5b70d8d545db1a3f): Executing operation View.AsMap/Flatten.PCollections
[INFO] 2018-03-14T19:52:43.453Z: (5b70d8d545db1d31): Executing operation View.AsMap/CreateDataflowView
[INFO] 2018-03-14T19:52:43.662Z: (5b70d8d545db1ebb): Executing operation PAssert$374/GroupGlobally/GatherAllOutputs/GroupByKey/Create
[INFO] 2018-03-14T19:52:43.828Z: (5b70d8d545db1225): Executing operation CreateMainInput/Read(CreateSource)+OutputSideInputs+PAssert$374/GroupGlobally/Window.Into()/Window.Assign+PAssert$374/GroupGlobally/GatherAllOutputs/Reify.Window/ParDo(Anonymous)+PAssert$374/GroupGlobally/GatherAllOutputs/WithKeys/AddKeys/Map+PAssert$374/GroupGlobally/GatherAllOutputs/Window.Into()/Window.Assign+PAssert$374/GroupGlobally/GatherAllOutputs/GroupByKey/Reify+PAssert$374/GroupGlobally/GatherAllOutputs/GroupByKey/Write
[INFO] 2018-03-14T19:52:49.591Z: (5b70d8d545db1809): Executing operation PAssert$374/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-14T19:52:49.647Z: (5b70d8d545db1791): Executing operation PAssert$374/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-14T19:52:49.798Z: (f626552758dda5af): Executing operation PAssert$374/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$374/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$374/GroupGlobally/GroupDummyAndContents/Reify+PAssert$374/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:52:49.834Z: (5b70d8d545db1e65): Executing operation PAssert$374/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$374/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$374/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$374/GroupGlobally/RewindowActuals/Window.Assign+PAssert$374/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$374/GroupGlobally/GroupDummyAndContents/Reify+PAssert$374/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:52:52.238Z: (69627fa7c145c3a7): Autoscaling: Raised the number of workers to 1 based on the rate of progress in the currently running step(s).
[INFO] 2018-03-14T19:52:52.142Z: (c40122c3cc68078a): Autoscaling: Raised the number of workers to 1 based on the rate of progress in the currently running step(s).
[INFO] 2018-03-14T19:52:55.596Z: (f626552758dda593): Executing operation PAssert$374/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-14T19:52:55.653Z: (5b70d8d545db1993): Executing operation PAssert$374/GroupGlobally/GroupDummyAndContents/Read+PAssert$374/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$374/GroupGlobally/Values/Values/Map+PAssert$374/GroupGlobally/ParDo(Concat)+PAssert$374/GetPane/Map+PAssert$374/RunChecks+PAssert$374/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-14T19:53:01.021Z: (e90978c6c97ac54c): Cleaning up.
[INFO] 2018-03-14T19:53:01.098Z: (e90978c6c97ac7b2): Stopping worker pool...
[INFO] 2018-03-14T19:53:06.807Z: (42c674b5e073a0e): Workers have started successfully.
[INFO] 2018-03-14T19:53:13.973Z: (56efb7ceb284aab3): Executing operation View.AsMap/BatchViewOverrides.GroupByWindowHashAsKeyAndWindowAsSortKey/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Close
[INFO] 2018-03-14T19:53:14.042Z: (93d5dc0e5862bd9c): Executing operation View.AsMap/BatchViewOverrides.GroupByWindowHashAsKeyAndWindowAsSortKey/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Read+View.AsMap/ParDo(ToMap)
[INFO] 2018-03-14T19:53:15.617Z: (65894607bcf3134e): Executing operation View.AsMap/GBKaSVForData/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Close
[INFO] 2018-03-14T19:53:15.701Z: (1e39e1b1e65fbbe5): Executing operation View.AsMap/GBKaSVForKeys/Create
[INFO] 2018-03-14T19:53:15.732Z: (65894607bcf31c77): Executing operation View.AsMap/GBKaSVForSize/Create
[INFO] 2018-03-14T19:53:15.908Z: (b8e9ef120ec50ece): Executing operation View.AsMap/GBKaSVForData/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Read+View.AsMap/ParMultiDo(ToIsmRecordForMapLike)+View.AsMap/GBKaSVForKeys/Write+View.AsMap/GBKaSVForSize/Write
[INFO] 2018-03-14T19:53:18.802Z: (93d5dc0e5862b976): Executing operation View.AsMap/CreateDataflowView
[INFO] 2018-03-14T19:53:18.987Z: (56efb7ceb284a527): Executing operation PAssert$376/GroupGlobally/GatherAllOutputs/GroupByKey/Create
[INFO] 2018-03-14T19:53:19.131Z: (56efb7ceb284a729): Executing operation Create1/Read(CreateSource)+OutputSideInputs+PAssert$376/GroupGlobally/Window.Into()/Window.Assign+PAssert$376/GroupGlobally/GatherAllOutputs/Reify.Window/ParDo(Anonymous)+PAssert$376/GroupGlobally/GatherAllOutputs/WithKeys/AddKeys/Map+PAssert$376/GroupGlobally/GatherAllOutputs/Window.Into()/Window.Assign+PAssert$376/GroupGlobally/GatherAllOutputs/GroupByKey/Reify+PAssert$376/GroupGlobally/GatherAllOutputs/GroupByKey/Write
[INFO] 2018-03-14T19:53:24.265Z: (56efb7ceb284a3f9): Executing operation PAssert$376/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-14T19:53:24.361Z: (56efb7ceb284a261): Executing operation PAssert$376/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-14T19:53:24.519Z: (56efb7ceb284a665): Executing operation PAssert$376/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$376/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$376/GroupGlobally/GroupDummyAndContents/Reify+PAssert$376/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:53:24.558Z: (93d5dc0e5862b067): Executing operation PAssert$376/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$376/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$376/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$376/GroupGlobally/RewindowActuals/Window.Assign+PAssert$376/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$376/GroupGlobally/GroupDummyAndContents/Reify+PAssert$376/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:53:24.719Z: (8045c514ce0d9b30): Workers have started successfully.
[INFO] 2018-03-14T19:53:26.065Z: (65894607bcf3153b): Executing operation View.AsMap/GBKaSVForKeys/Close
[INFO] 2018-03-14T19:53:26.114Z: (1e39e1b1e65fb441): Executing operation View.AsMap/GBKaSVForSize/Close
[INFO] 2018-03-14T19:53:26.181Z: (65894607bcf31e22): Executing operation View.AsMap/GBKaSVForKeys/Read+View.AsMap/ParDo(ToIsmMetadataRecordForKey)
[INFO] 2018-03-14T19:53:26.237Z: (1e39e1b1e65fbfcc): Executing operation View.AsMap/GBKaSVForSize/Read+View.AsMap/ParDo(ToIsmMetadataRecordForSize)
[INFO] 2018-03-14T19:53:28.401Z: (aac1edab24a75107): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-14T19:53:28.430Z: (aac1edab24a75fa5): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-14T19:53:33.601Z: (93d5dc0e5862b1ac): Executing operation PAssert$376/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-14T19:53:33.665Z: (93d5dc0e5862b799): Executing operation PAssert$376/GroupGlobally/GroupDummyAndContents/Read+PAssert$376/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$376/GroupGlobally/Values/Values/Map+PAssert$376/GroupGlobally/ParDo(Concat)+PAssert$376/GetPane/Map+PAssert$376/RunChecks+PAssert$376/VerifyAssertions/ParDo(DefaultConclude)
[INFO] Job 2018-03-14_12_50_14-11646622445018756216 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_50_14-11646622445018756216. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-14T19:53:37.805Z: (8dbac6578ad13b99): Executing operation View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Combine.perKey(Singleton)/GroupByKey/Close
[INFO] 2018-03-14T19:53:37.870Z: (8dbac6578ad13d31): Executing operation View.AsSingleton/Combine.GloballyAsSingletonView/BatchViewOverrides.GroupByWindowHashAsKeyAndWindowAsSortKey/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Create
[INFO] 2018-03-14T19:53:38.029Z: (229bcc187051eab3): Executing operation View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Combine.perKey(Singleton)/GroupByKey/Read+View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Combine.perKey(Singleton)/Combine.GroupedValues+View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Combine.perKey(Singleton)/Combine.GroupedValues/Extract+View.AsSingleton/Combine.GloballyAsSingletonView/Combine.globally(Singleton)/Values/Values/Map+View.AsSingleton/Combine.GloballyAsSingletonView/BatchViewOverrides.GroupByWindowHashAsKeyAndWindowAsSortKey/ParDo(UseWindowHashAsKeyAndWindowAsSortKey)+View.AsSingleton/Combine.GloballyAsSingletonView/BatchViewOverrides.GroupByWindowHashAsKeyAndWindowAsSortKey/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Write
[INFO] 2018-03-14T19:53:36.016Z: (65894607bcf31919): Executing operation View.AsMap/Flatten.PCollections
[INFO] 2018-03-14T19:53:36.257Z: (65894607bcf3138c): Executing operation View.AsMap/CreateDataflowView
[INFO] 2018-03-14T19:53:36.467Z: (9d53503ec986849f): Executing operation PAssert$375/GroupGlobally/GatherAllOutputs/GroupByKey/Create
[INFO] 2018-03-14T19:53:36.630Z: (65894607bcf318f6): Executing operation CreateMainInput/Create.Values/Read(CreateSource)+CreateMainInput/ParDo(ConvertTimestamps)+MainWindowInto/Window.Assign+OutputSideInputs+PAssert$375/GroupGlobally/Window.Into()/Window.Assign+PAssert$375/GroupGlobally/GatherAllOutputs/Reify.Window/ParDo(Anonymous)+PAssert$375/GroupGlobally/GatherAllOutputs/WithKeys/AddKeys/Map+PAssert$375/GroupGlobally/GatherAllOutputs/Window.Into()/Window.Assign+PAssert$375/GroupGlobally/GatherAllOutputs/GroupByKey/Reify+PAssert$375/GroupGlobally/GatherAllOutputs/GroupByKey/Write
[INFO] 2018-03-14T19:53:39.991Z: (3e8882f6fe1c189c): Cleaning up.
[INFO] 2018-03-14T19:53:40.080Z: (3e8882f6fe1c1a42): Stopping worker pool...
[INFO] 2018-03-14T19:53:42.448Z: (65894607bcf31d9a): Executing operation PAssert$375/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-14T19:53:42.525Z: (65894607bcf31ea2): Executing operation PAssert$375/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-14T19:53:42.693Z: (1e39e1b1e65fbb81): Executing operation PAssert$375/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$375/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$375/GroupGlobally/GroupDummyAndContents/Reify+PAssert$375/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:53:42.703Z: (532452256530f8bf): Executing operation View.AsMultimap/GBKaSVForData/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Close
[INFO] 2018-03-14T19:53:42.805Z: (532452256530f66e): Executing operation View.AsMultimap/GBKaSVForSize/Create
[INFO] 2018-03-14T19:53:42.842Z: (b6daa4861df088f5): Executing operation View.AsMultimap/GBKaSVForKeys/Create
[INFO] 2018-03-14T19:53:43.036Z: (b6daa4861df08214): Executing operation View.AsMultimap/GBKaSVForData/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Read+View.AsMultimap/ParMultiDo(ToIsmRecordForMapLike)+View.AsMultimap/GBKaSVForSize/Write+View.AsMultimap/GBKaSVForKeys/Write
[INFO] 2018-03-14T19:53:42.737Z: (65894607bcf3180d): Executing operation PAssert$375/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$375/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$375/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$375/GroupGlobally/RewindowActuals/Window.Assign+PAssert$375/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$375/GroupGlobally/GroupDummyAndContents/Reify+PAssert$375/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:53:45.498Z: (229bcc187051e315): Executing operation View.AsSingleton/Combine.GloballyAsSingletonView/BatchViewOverrides.GroupByWindowHashAsKeyAndWindowAsSortKey/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Close
[INFO] 2018-03-14T19:53:45.557Z: (8dbac6578ad13360): Executing operation View.AsSingleton/Combine.GloballyAsSingletonView/BatchViewOverrides.GroupByWindowHashAsKeyAndWindowAsSortKey/BatchViewOverrides.GroupByKeyAndSortValuesOnly/Read+View.AsSingleton/Combine.GloballyAsSingletonView/ParDo(IsmRecordForSingularValuePerWindow)
[INFO] 2018-03-14T19:53:48.484Z: (1e39e1b1e65fb3dd): Executing operation PAssert$375/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-14T19:53:48.570Z: (1e39e1b1e65fbf68): Executing operation PAssert$375/GroupGlobally/GroupDummyAndContents/Read+PAssert$375/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$375/GroupGlobally/Values/Values/Map+PAssert$375/GroupGlobally/ParDo(Concat)+PAssert$375/GetPane/Map+PAssert$375/RunChecks+PAssert$375/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-14T19:53:53.160Z: (9792866dc77d89e9): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-14T19:53:53.177Z: (9792866dc77d8273): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-14T19:53:53.316Z: (532452256530fc0a): Executing operation View.AsMultimap/GBKaSVForSize/Close
[INFO] 2018-03-14T19:53:53.370Z: (b6daa4861df0888c): Executing operation View.AsMultimap/GBKaSVForKeys/Close
[INFO] 2018-03-14T19:53:53.412Z: (532452256530fdcb): Executing operation View.AsMultimap/GBKaSVForSize/Read+View.AsMultimap/ParDo(ToIsmMetadataRecordForSize)
[INFO] 2018-03-14T19:53:53.446Z: (b6daa4861df08e6f): Executing operation View.AsMultimap/GBKaSVForKeys/Read+View.AsMultimap/ParDo(ToIsmMetadataRecordForKey)
[INFO] 2018-03-14T19:53:53.921Z: (f60686b3d9631320): Cleaning up.
[INFO] 2018-03-14T19:53:54.012Z: (f60686b3d9631ce6): Stopping worker pool...
[INFO] 2018-03-14T19:53:57.221Z: (8dbac6578ad1322a): Executing operation View.AsSingleton/Combine.GloballyAsSingletonView/CreateDataflowView
[INFO] 2018-03-14T19:53:57.360Z: (8dbac6578ad1355a): Executing operation PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Create
[INFO] 2018-03-14T19:53:57.478Z: (8dbac6578ad13b58): Executing operation Create123/Read(CreateSource)+OutputSideInputs+PAssert$378/GroupGlobally/Window.Into()/Window.Assign+PAssert$378/GroupGlobally/GatherAllOutputs/Reify.Window/ParDo(Anonymous)+PAssert$378/GroupGlobally/GatherAllOutputs/WithKeys/AddKeys/Map+PAssert$378/GroupGlobally/GatherAllOutputs/Window.Into()/Window.Assign+PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Reify+PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Write
[INFO] 2018-03-14T19:53:56.989Z: (4d595e5d02bc4f17): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-14T19:53:57.006Z: (4d595e5d02bc4d45): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-14T19:53:57.051Z: (665fb5a625a265cf): Worker pool stopped.
[INFO] Job 2018-03-14_12_50_39-3401096982983726142 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_50_39-3401096982983726142. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-14T19:54:02.178Z: (8dbac6578ad13455): Executing operation PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-14T19:54:02.230Z: (229bcc187051e914): Executing operation PAssert$378/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-14T19:54:02.378Z: (8dbac6578ad131b8): Executing operation PAssert$378/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$378/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$378/GroupGlobally/GroupDummyAndContents/Reify+PAssert$378/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:54:02.402Z: (229bcc187051e561): Executing operation PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$378/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$378/GroupGlobally/RewindowActuals/Window.Assign+PAssert$378/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$378/GroupGlobally/GroupDummyAndContents/Reify+PAssert$378/GroupGlobally/GroupDummyAndContents/Write
[INFO] Job 2018-03-14_12_50_42-10406093558231026070 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_50_42-10406093558231026070. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-14T19:54:06.004Z: (532452256530fefc): Executing operation View.AsMultimap/Flatten.PCollections
[INFO] 2018-03-14T19:54:06.254Z: (b6daa4861df08937): Executing operation View.AsMultimap/CreateDataflowView
[INFO] 2018-03-14T19:54:06.463Z: (4bbb299c0f443f96): Executing operation PAssert$377/GroupGlobally/GatherAllOutputs/GroupByKey/Create
[INFO] 2018-03-14T19:54:06.644Z: (4bbb299c0f4432e1): Executing operation CreateMainInput/Read(CreateSource)+OutputSideInputs+PAssert$377/GroupGlobally/Window.Into()/Window.Assign+PAssert$377/GroupGlobally/GatherAllOutputs/Reify.Window/ParDo(Anonymous)+PAssert$377/GroupGlobally/GatherAllOutputs/WithKeys/AddKeys/Map+PAssert$377/GroupGlobally/GatherAllOutputs/Window.Into()/Window.Assign+PAssert$377/GroupGlobally/GatherAllOutputs/GroupByKey/Reify+PAssert$377/GroupGlobally/GatherAllOutputs/GroupByKey/Write
[INFO] 2018-03-14T19:54:06.430Z: (8dbac6578ad13c1c): Executing operation PAssert$378/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-14T19:54:06.496Z: (229bcc187051edc3): Executing operation PAssert$378/GroupGlobally/GroupDummyAndContents/Read+PAssert$378/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$378/GroupGlobally/Values/Values/Map+PAssert$378/GroupGlobally/ParDo(Concat)+PAssert$378/GetPane/Map+PAssert$378/RunChecks+PAssert$378/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-14T19:54:08.967Z: (a9c8b410dd754f4c): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-14T19:54:08.989Z: (a9c8b410dd754c36): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-14T19:54:11.097Z: (4bbb299c0f443ba5): Executing operation PAssert$377/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-14T19:54:11.171Z: (4bbb299c0f4432ed): Executing operation PAssert$377/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-14T19:54:11.326Z: (532452256530f7c1): Executing operation PAssert$377/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$377/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$377/GroupGlobally/GroupDummyAndContents/Reify+PAssert$377/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:54:11.365Z: (4bbb299c0f443ef0): Executing operation PAssert$377/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$377/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$377/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$377/GroupGlobally/RewindowActuals/Window.Assign+PAssert$377/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$377/GroupGlobally/GroupDummyAndContents/Reify+PAssert$377/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-14T19:54:11.624Z: (4d83e54bcc3cd090): Cleaning up.
[INFO] 2018-03-14T19:54:11.697Z: (4d83e54bcc3cd322): Stopping worker pool...
[INFO] Job 2018-03-14_12_50_47-6568253716251402234 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_50_47-6568253716251402234. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-14T19:54:18.027Z: (532452256530fd4c): Executing operation PAssert$377/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-14T19:54:18.106Z: (532452256530ff0d): Executing operation PAssert$377/GroupGlobally/GroupDummyAndContents/Read+PAssert$377/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$377/GroupGlobally/Values/Values/Map+PAssert$377/GroupGlobally/ParDo(Concat)+PAssert$377/GetPane/Map+PAssert$377/RunChecks+PAssert$377/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-14T19:54:22.256Z: (9cc031303e5581dc): Cleaning up.
[INFO] 2018-03-14T19:54:22.364Z: (9cc031303e5580fe): Stopping worker pool...
[INFO] 2018-03-14T19:54:24.570Z: (2ebbac6af3b9dc10): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-14T19:54:24.603Z: (2ebbac6af3b9dfb6): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-14T19:54:24.639Z: (e90978c6c97ac03a): Worker pool stopped.
[INFO] Job 2018-03-14_12_50_50-6540511996773130932 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_50_50-6540511996773130932. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-14T19:55:19.204Z: (be5cde4905638056): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-14T19:55:19.229Z: (be5cde490563844c): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-14T19:55:19.274Z: (3e8882f6fe1c11ca): Worker pool stopped.
[INFO] 2018-03-14T19:55:20.704Z: (abd085ec4dfce562): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-14T19:55:20.725Z: (abd085ec4dfcecd8): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-14T19:55:20.761Z: (f60686b3d96319ee): Worker pool stopped.
[INFO] Job 2018-03-14_12_51_43-18117146143562899874 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_51_43-18117146143562899874. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] Job 2018-03-14_12_51_40-18064144981435002937 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_51_40-18064144981435002937. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-14T19:55:37.072Z: (69627fa7c145c29e): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-14T19:55:37.097Z: (69627fa7c145c99c): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-14T19:55:37.143Z: (9cc031303e558fd6): Worker pool stopped.
[INFO] Job 2018-03-14_12_52_04-14451414626259457078 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_52_04-14451414626259457078. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-14T19:55:46.555Z: (c40122c3cc68061d): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-14T19:55:46.579Z: (c40122c3cc680493): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] Job 2018-03-14_12_52_06-16900596753492953054 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_12_52_06-16900596753492953054. Found 1 success, 0 failures out of 1 expected assertions.
2018-03-14T19:55:54.674 [INFO] Running org.apache.beam.sdk.transforms.ViewTest
2018-03-14T19:55:54.701 [INFO] Tests run: 34, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 746.323 s - in org.apache.beam.sdk.transforms.ViewTest
2018-03-14T19:55:54.702 [INFO] Running org.apache.beam.sdk.values.PDoneTest
2018-03-14T19:55:54.709 [WARNING] Tests run: 1, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0 s - in org.apache.beam.sdk.values.PDoneTest
2018-03-14T19:55:54.744 [INFO] 
2018-03-14T19:55:54.745 [INFO] Results:
2018-03-14T19:55:54.745 [INFO] 
2018-03-14T19:55:54.746 [WARNING] Tests run: 305, Failures: 0, Errors: 0, Skipped: 2
2018-03-14T19:55:54.746 [INFO] 
[JENKINS] Recording test results
2018-03-14T19:55:57.052 [INFO] 
2018-03-14T19:55:57.052 [INFO] --- maven-dependency-plugin:3.0.2:analyze-only (default) @ beam-runners-google-cloud-dataflow-java ---
2018-03-14T19:55:57.494 [INFO] No dependency problems found
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
2018-03-14T19:56:13.045 [INFO] ------------------------------------------------------------------------
2018-03-14T19:56:13.045 [INFO] Reactor Summary:
2018-03-14T19:56:13.045 [INFO] 
2018-03-14T19:56:13.046 [INFO] Apache Beam :: Parent .............................. SUCCESS [  7.043 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: SDKs :: Java :: Build Tools ......... SUCCESS [  6.120 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: Model ............................... SUCCESS [  1.775 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: Model :: Pipeline ................... SUCCESS [  8.293 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: Model :: Job Management ............. SUCCESS [  5.962 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: Model :: Fn Execution ............... SUCCESS [  8.022 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: SDKs ................................ SUCCESS [  2.195 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: SDKs :: Java ........................ SUCCESS [  2.034 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: SDKs :: Java :: Core ................ SUCCESS [02:06 min]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: SDKs :: Java :: Extensions .......... SUCCESS [  2.179 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: SDKs :: Java :: Extensions :: Google Cloud Platform Core SUCCESS [ 10.651 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: Runners ............................. SUCCESS [  1.944 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: Runners :: Core Construction Java ... SUCCESS [ 13.608 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: Runners :: Core Java ................ SUCCESS [ 30.809 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: SDKs :: Java :: IO .................. SUCCESS [  2.072 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: Runners :: Local Java Core .......... SUCCESS [  4.611 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: Runners :: Direct Java .............. SUCCESS [05:46 min]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: SDKs :: Java :: Extensions :: Protobuf SUCCESS [ 11.850 s]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: SDKs :: Java :: IO :: Google Cloud Platform SUCCESS [02:14 min]
2018-03-14T19:56:13.046 [INFO] Apache Beam :: Runners :: Google Cloud Dataflow .... SUCCESS [  01:40 h]
2018-03-14T19:56:13.046 [INFO] ------------------------------------------------------------------------
2018-03-14T19:56:13.047 [INFO] BUILD SUCCESS
2018-03-14T19:56:13.047 [INFO] ------------------------------------------------------------------------
2018-03-14T19:56:13.047 [INFO] Total time: 01:54 h
2018-03-14T19:56:13.047 [INFO] Finished at: 2018-03-14T19:56:13Z
[JENKINS] Archiving disabled
2018-03-14T19:56:18.264 [INFO] Final Memory: 575M/2615M
2018-03-14T19:56:18.264 [INFO] ------------------------------------------------------------------------
channel stopped

Jenkins build is back to stable : beam_PostCommit_Java_ValidatesRunner_Dataflow #5155

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/beam_PostCommit_Java_ValidatesRunner_Dataflow/5155/display/redirect?page=changes>

Jenkins build is unstable: beam_PostCommit_Java_ValidatesRunner_Dataflow #5154

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/beam_PostCommit_Java_ValidatesRunner_Dataflow/5154/display/redirect?page=changes>

Build failed in Jenkins: beam_PostCommit_Java_ValidatesRunner_Dataflow #5153

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/beam_PostCommit_Java_ValidatesRunner_Dataflow/5153/display/redirect?page=changes>

Changes:

[tgroh] Add an InProcess SdkHarness Rule

[tgroh] Close Outstanding Control Clients on Service close

[tgroh] Don't try to close a cancelled Multiplexer

[tgroh] fixup! Close Outstanding Control Clients on Service close

[tgroh] fixup! Don't try to close a cancelled Multiplexer

[tgroh] fixup! Add an InProcess SdkHarness Rule

[tgroh] fixup! Don't try to close a cancelled Multiplexer

[ehudm] Use Python 3 compatible string.

------------------------------------------
[...truncated 24.87 MB...]
[INFO] 2018-03-15T02:02:39.582Z: (b6198bbdcd056431): Fusing consumer PAssert$377/GroupGlobally/GroupDummyAndContents/Reify into PAssert$377/GroupGlobally/WindowIntoDummy/Window.Assign
[INFO] 2018-03-15T02:02:39.616Z: (b6198bbdcd056c57): Fusing consumer PAssert$377/GroupGlobally/WindowIntoDummy/Window.Assign into PAssert$377/GroupGlobally/Create.Values/Read(CreateSource)
[INFO] 2018-03-15T02:02:40.122Z: (15244c67deeb0900): Executing operation PAssert$377/GroupGlobally/GatherAllOutputs/GroupByKey/Create
[INFO] 2018-03-15T02:02:40.190Z: (15f7ab0cbc7b6f10): Starting 1 workers in us-central1-f...
[INFO] 2018-03-15T02:02:40.326Z: (5c5f5f711bf28ed4): Executing operation Create.Values/Read(CreateSource)+KvSwap/KvSwap/Map+PAssert$377/GroupGlobally/Window.Into()/Window.Assign+PAssert$377/GroupGlobally/GatherAllOutputs/Reify.Window/ParDo(Anonymous)+PAssert$377/GroupGlobally/GatherAllOutputs/WithKeys/AddKeys/Map+PAssert$377/GroupGlobally/GatherAllOutputs/Window.Into()/Window.Assign+PAssert$377/GroupGlobally/GatherAllOutputs/GroupByKey/Reify+PAssert$377/GroupGlobally/GatherAllOutputs/GroupByKey/Write
[INFO] 2018-03-15T02:02:48.524Z: (89bb1449654ebd73): Cleaning up.
[INFO] 2018-03-15T02:02:48.608Z: (89bb1449654eb311): Stopping worker pool...
[INFO] 2018-03-15T02:02:48.509Z: Autoscaling: Raised the number of workers to 0 based on the rate of progress in the currently running step(s).
[INFO] 2018-03-15T02:02:48.909Z: (59a51e12d1f4ecd4): Executing operation PAssert$370/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-15T02:02:48.987Z: (59a51e12d1f4e9f5): Executing operation PAssert$370/GroupGlobally/GroupDummyAndContents/Read+PAssert$370/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$370/GroupGlobally/Values/Values/Map+PAssert$370/GroupGlobally/ParDo(Concat)+PAssert$370/GetPane/Map+PAssert$370/RunChecks+PAssert$370/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-15T02:02:50.731Z: (1024ff05ad78a0ce): Workers have started successfully.
[INFO] 2018-03-15T02:02:50.154Z: (7698105668e5849e): Workers have started successfully.
[INFO] 2018-03-15T02:02:51.517Z: (ddb7cdb99ad8cb97): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-15T02:02:51.540Z: (ddb7cdb99ad8c3bd): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-15T02:02:43.075Z: (cd90d2b09e9933fb): Autoscaling is enabled for job 2018-03-14_19_02_43-14812570830438480351. The number of workers will be between 1 and 100.
[INFO] 2018-03-15T02:02:43.096Z: (cd90d2b09e993a82): Autoscaling was automatically enabled for job 2018-03-14_19_02_43-14812570830438480351.
[INFO] 2018-03-15T02:02:45.664Z: (b74543d965bf982a): Checking required Cloud APIs are enabled.
[INFO] 2018-03-15T02:02:45.752Z: (b74543d965bf97d3): Checking permissions granted to controller Service Account.
[INFO] 2018-03-15T02:02:49.866Z: (b74543d965bf9933): Expanding CoGroupByKey operations into optimizable parts.
[INFO] 2018-03-15T02:02:49.893Z: (b74543d965bf98dc): Expanding GroupByKey operations into optimizable parts.
[INFO] 2018-03-15T02:02:49.920Z: (b74543d965bf967a): Lifting ValueCombiningMappingFns into MergeBucketsMappingFns
[INFO] 2018-03-15T02:02:49.985Z: (b74543d965bf9a90): Fusing adjacent ParDo, Read, Write, and Flatten operations
[INFO] 2018-03-15T02:02:50.011Z: (b74543d965bf982e): Elided trivial flatten 
[INFO] 2018-03-15T02:02:50.031Z: (b74543d965bf95cc): Elided trivial flatten 
[INFO] 2018-03-15T02:02:50.070Z: (b74543d965bf936a): Elided trivial flatten 
[INFO] 2018-03-15T02:02:50.092Z: (b74543d965bf9108): Unzipping flatten s15 for input s10.org.apache.beam.sdk.values.PCollection.<init>:329#89dcb817761b7ddd
[INFO] 2018-03-15T02:02:50.118Z: (b74543d965bf9ea6): Fusing unzipped copy of PAssert$378/GroupGlobally/GroupDummyAndContents/Reify, through flatten PAssert$378/GroupGlobally/FlattenDummyAndContents, into producer PAssert$378/GroupGlobally/KeyForDummy/AddKeys/Map
[INFO] 2018-03-15T02:02:50.141Z: (b74543d965bf9c44): Unzipping flatten s15-u22 for input s17-reify-value0-c20
[INFO] 2018-03-15T02:02:50.163Z: (b74543d965bf99e2): Fusing unzipped copy of PAssert$378/GroupGlobally/GroupDummyAndContents/Write, through flatten s15-u22, into producer PAssert$378/GroupGlobally/GroupDummyAndContents/Reify
[INFO] 2018-03-15T02:02:50.183Z: (b74543d965bf9780): Fusing consumer PAssert$378/GetPane/Map into PAssert$378/GroupGlobally/ParDo(Concat)
[INFO] 2018-03-15T02:02:50.198Z: (b74543d965bf951e): Fusing consumer PAssert$378/VerifyAssertions/ParDo(DefaultConclude) into PAssert$378/RunChecks
[INFO] 2018-03-15T02:02:50.220Z: (b74543d965bf92bc): Fusing consumer PAssert$378/RunChecks into PAssert$378/GetPane/Map
[INFO] 2018-03-15T02:02:50.244Z: (b74543d965bf905a): Fusing consumer PAssert$378/GroupGlobally/GroupDummyAndContents/GroupByWindow into PAssert$378/GroupGlobally/GroupDummyAndContents/Read
[INFO] 2018-03-15T02:02:50.268Z: (b74543d965bf9df8): Fusing consumer PAssert$378/GroupGlobally/Values/Values/Map into PAssert$378/GroupGlobally/GroupDummyAndContents/GroupByWindow
[INFO] 2018-03-15T02:02:50.296Z: (b74543d965bf9b96): Fusing consumer PAssert$378/GroupGlobally/ParDo(Concat) into PAssert$378/GroupGlobally/Values/Values/Map
[INFO] 2018-03-15T02:02:50.325Z: (b74543d965bf9934): Fusing consumer PAssert$378/GroupGlobally/GatherAllOutputs/Reify.Window/ParDo(Anonymous) into PAssert$378/GroupGlobally/Window.Into()/Window.Assign
[INFO] 2018-03-15T02:02:50.349Z: (b74543d965bf96d2): Fusing consumer PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Write into PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Reify
[INFO] 2018-03-15T02:02:50.376Z: (b74543d965bf9470): Fusing consumer PAssert$378/GroupGlobally/RewindowActuals/Window.Assign into PAssert$378/GroupGlobally/GatherAllOutputs/Values/Values/Map
[INFO] 2018-03-15T02:02:50.388Z: (b74543d965bf920e): Fusing consumer KvSwap/KvSwap/Map into Create.Values/Read(CreateSource)
[INFO] 2018-03-15T02:02:50.407Z: (b74543d965bf9fac): Fusing consumer PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Reify into PAssert$378/GroupGlobally/GatherAllOutputs/Window.Into()/Window.Assign
[INFO] 2018-03-15T02:02:50.430Z: (b74543d965bf9d4a): Fusing consumer PAssert$378/GroupGlobally/GatherAllOutputs/WithKeys/AddKeys/Map into PAssert$378/GroupGlobally/GatherAllOutputs/Reify.Window/ParDo(Anonymous)
[INFO] 2018-03-15T02:02:50.446Z: (b74543d965bf9ae8): Fusing consumer PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow into PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Read
[INFO] 2018-03-15T02:02:50.469Z: (b74543d965bf9886): Fusing consumer PAssert$378/GroupGlobally/KeyForDummy/AddKeys/Map into PAssert$378/GroupGlobally/RewindowActuals/Window.Assign
[INFO] 2018-03-15T02:02:50.496Z: (b74543d965bf9624): Fusing consumer PAssert$378/GroupGlobally/GatherAllOutputs/Window.Into()/Window.Assign into PAssert$378/GroupGlobally/GatherAllOutputs/WithKeys/AddKeys/Map
[INFO] 2018-03-15T02:02:50.516Z: (b74543d965bf93c2): Fusing consumer PAssert$378/GroupGlobally/GatherAllOutputs/Values/Values/Map into PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow
[INFO] 2018-03-15T02:02:50.537Z: (b74543d965bf9160): Fusing consumer PAssert$378/GroupGlobally/Window.Into()/Window.Assign into KvSwap/KvSwap/Map
[INFO] 2018-03-15T02:02:50.562Z: (b74543d965bf9efe): Fusing consumer PAssert$378/GroupGlobally/GroupDummyAndContents/Reify into PAssert$378/GroupGlobally/WindowIntoDummy/Window.Assign
[INFO] 2018-03-15T02:02:50.585Z: (b74543d965bf9c9c): Fusing consumer PAssert$378/GroupGlobally/GroupDummyAndContents/Write into PAssert$378/GroupGlobally/GroupDummyAndContents/Reify
[INFO] 2018-03-15T02:02:50.614Z: (b74543d965bf9a3a): Fusing consumer PAssert$378/GroupGlobally/WindowIntoDummy/Window.Assign into PAssert$378/GroupGlobally/Create.Values/Read(CreateSource)
[INFO] 2018-03-15T02:02:50.850Z: (403306f9507652c1): Executing operation PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Create
[INFO] 2018-03-15T02:02:50.897Z: (a528910def134314): Starting 1 workers in us-central1-f...
[INFO] 2018-03-15T02:02:50.996Z: (403306f950765ec6): Executing operation Create.Values/Read(CreateSource)+KvSwap/KvSwap/Map+PAssert$378/GroupGlobally/Window.Into()/Window.Assign+PAssert$378/GroupGlobally/GatherAllOutputs/Reify.Window/ParDo(Anonymous)+PAssert$378/GroupGlobally/GatherAllOutputs/WithKeys/AddKeys/Map+PAssert$378/GroupGlobally/GatherAllOutputs/Window.Into()/Window.Assign+PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Reify+PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Write
[INFO] 2018-03-15T02:02:54.391Z: (81cd215cf11ead98): Cleaning up.
[INFO] 2018-03-15T02:02:54.476Z: (81cd215cf11ea852): Stopping worker pool...
[INFO] 2018-03-15T02:02:57.597Z: (59d7f5c28f2a028c): Workers have started successfully.
[INFO] 2018-03-15T02:02:58.496Z: (89933a2b8509afbe): Autoscaling: Raised the number of workers to 0 based on the rate of progress in the currently running step(s).
[INFO] 2018-03-15T02:02:56.440Z: (1b7deb908b3b0090): Executing operation PAssert$369/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-15T02:02:56.548Z: (1b7deb908b3b0a4f): Executing operation PAssert$369/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$369/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$369/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$369/GroupGlobally/RewindowActuals/Window.Assign+PAssert$369/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$369/GroupGlobally/GroupDummyAndContents/Reify+PAssert$369/GroupGlobally/GroupDummyAndContents/Write
[INFO] Job 2018-03-14_18_59_20-8436897312347400571 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_18_59_20-8436897312347400571. Found 2 success, 0 failures out of 2 expected assertions.
[INFO] 2018-03-15T02:02:59.452Z: (4b4c083d45f4a349): Workers have started successfully.
[INFO] 2018-03-15T02:02:59.413Z: (1b7deb908b3b0dcd): Executing operation PAssert$369/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-15T02:02:59.478Z: (e525c337024bd9f9): Executing operation PAssert$369/GroupGlobally/GroupDummyAndContents/Read+PAssert$369/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$369/GroupGlobally/Values/Values/Map+PAssert$369/GroupGlobally/ParDo(Concat)+PAssert$369/GetPane/Map+PAssert$369/RunChecks+PAssert$369/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-15T02:03:00.387Z: (18aaef27924d54fe): Autoscaling: Raised the number of workers to 3 based on the rate of progress in the currently running step(s).
[INFO] 2018-03-15T02:03:04.378Z: (3643a36850f7cf6f): Autoscaling: Raised the number of workers to 1 based on the rate of progress in the currently running step(s).
[INFO] 2018-03-15T02:03:04.454Z: (6c11a49e7cb3b407): Cleaning up.
[INFO] 2018-03-15T02:03:04.542Z: (6c11a49e7cb3baed): Stopping worker pool...
[INFO] 2018-03-15T02:03:14.590Z: (89933a2b8509a295): Autoscaling: Raised the number of workers to 1 based on the rate of progress in the currently running step(s).
[INFO] 2018-03-15T02:03:14.037Z: (f1bb10888e0d77e6): Executing operation GroupByKey/Close
[INFO] 2018-03-15T02:03:14.118Z: (f1bb10888e0d7b93): Executing operation GroupByKey/Read+GroupByKey/GroupByWindow+ParDo(Anonymous)
[INFO] 2018-03-15T02:03:20.143Z: (8f9820b270878934): Cleaning up.
[INFO] 2018-03-15T02:03:20.252Z: (8f9820b270878f6e): Stopping worker pool...
[INFO] 2018-03-15T02:03:22.917Z: (da26dcfa5a06805f): Executing operation Combine.globally(Count)/Combine.perKey(Count)/GroupByKey/Close
[INFO] 2018-03-15T02:03:22.976Z: (c943bcdcdc44d891): Executing operation PAssert$371/GroupGlobally/GatherAllOutputs/GroupByKey/Create
[INFO] 2018-03-15T02:03:23.111Z: (c943bcdcdc44d4ea): Executing operation Combine.globally(Count)/Combine.perKey(Count)/GroupByKey/Read+Combine.globally(Count)/Combine.perKey(Count)/Combine.GroupedValues+Combine.globally(Count)/Values/Values/Map+PAssert$371/GroupGlobally/Window.Into()/Window.Assign+PAssert$371/GroupGlobally/GatherAllOutputs/Reify.Window/ParDo(Anonymous)+PAssert$371/GroupGlobally/GatherAllOutputs/WithKeys/AddKeys/Map+PAssert$371/GroupGlobally/GatherAllOutputs/Window.Into()/Window.Assign+PAssert$371/GroupGlobally/GatherAllOutputs/GroupByKey/Reify+PAssert$371/GroupGlobally/GatherAllOutputs/GroupByKey/Write
[INFO] 2018-03-15T02:03:29.789Z: (c943bcdcdc44d044): Executing operation PAssert$371/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-15T02:03:29.854Z: (c943bcdcdc44dd9c): Executing operation PAssert$371/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-15T02:03:29.998Z: (da26dcfa5a068a89): Executing operation PAssert$371/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$371/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$371/GroupGlobally/GroupDummyAndContents/Reify+PAssert$371/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:30.037Z: (c943bcdcdc44d6f8): Executing operation PAssert$371/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$371/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$371/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$371/GroupGlobally/RewindowActuals/Window.Assign+PAssert$371/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$371/GroupGlobally/GroupDummyAndContents/Reify+PAssert$371/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:33.228Z: (cbfbe6502c22d593): Workers have started successfully.
[INFO] 2018-03-15T02:03:35.340Z: (c943bcdcdc44d252): Executing operation PAssert$371/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-15T02:03:35.419Z: (c943bcdcdc44dfff): Executing operation PAssert$371/GroupGlobally/GroupDummyAndContents/Read+PAssert$371/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$371/GroupGlobally/Values/Values/Map+PAssert$371/GroupGlobally/ParDo(Concat)+PAssert$371/GetPane/Map+PAssert$371/RunChecks+PAssert$371/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-15T02:03:40.811Z: Executing operation PAssert$374/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-15T02:03:40.836Z: Executing operation PAssert$375/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-15T02:03:40.857Z: Executing operation PAssert$373/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-15T02:03:40.880Z: Executing operation PAssert$372/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-15T02:03:40.905Z: Executing operation PAssert$374/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-15T02:03:40.927Z: Executing operation PAssert$375/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-15T02:03:40.949Z: Executing operation PAssert$373/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-15T02:03:40.969Z: Executing operation PAssert$372/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-15T02:03:41.063Z: Executing operation PAssert$374/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$374/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$374/GroupGlobally/GroupDummyAndContents/Reify+PAssert$374/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:41.088Z: Executing operation PAssert$374/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$374/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$374/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$374/GroupGlobally/RewindowActuals/Window.Assign+PAssert$374/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$374/GroupGlobally/GroupDummyAndContents/Reify+PAssert$374/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:41.142Z: Executing operation PAssert$375/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$375/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$375/GroupGlobally/GroupDummyAndContents/Reify+PAssert$375/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:41.164Z: Executing operation PAssert$375/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$375/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$375/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$375/GroupGlobally/RewindowActuals/Window.Assign+PAssert$375/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$375/GroupGlobally/GroupDummyAndContents/Reify+PAssert$375/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:41.188Z: Executing operation PAssert$373/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$373/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$373/GroupGlobally/GroupDummyAndContents/Reify+PAssert$373/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:41.210Z: Executing operation PAssert$373/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$373/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$373/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$373/GroupGlobally/RewindowActuals/Window.Assign+PAssert$373/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$373/GroupGlobally/GroupDummyAndContents/Reify+PAssert$373/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:41.236Z: Executing operation PAssert$372/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$372/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$372/GroupGlobally/GroupDummyAndContents/Reify+PAssert$372/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:41.258Z: Executing operation PAssert$372/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$372/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$372/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$372/GroupGlobally/RewindowActuals/Window.Assign+PAssert$372/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$372/GroupGlobally/GroupDummyAndContents/Reify+PAssert$372/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:39.644Z: (6eed8dfb07834927): Cleaning up.
[INFO] 2018-03-15T02:03:39.699Z: (6eed8dfb07834e19): Stopping worker pool...
[INFO] 2018-03-15T02:03:42.788Z: Executing operation Combine.globally(Count)/Combine.perKey(Count)/GroupByKey/Close
[INFO] 2018-03-15T02:03:42.865Z: Executing operation Combine.globally(Count)/Combine.perKey(Count)/GroupByKey/Read+Combine.globally(Count)/Combine.perKey(Count)/Combine.GroupedValues+Combine.globally(Count)/Values/Values/Map+PAssert$376/GroupGlobally/Window.Into()/Window.Assign+PAssert$376/GroupGlobally/GatherAllOutputs/Reify.Window/ParDo(Anonymous)+PAssert$376/GroupGlobally/GatherAllOutputs/WithKeys/AddKeys/Map+PAssert$376/GroupGlobally/GatherAllOutputs/Window.Into()/Window.Assign+PAssert$376/GroupGlobally/GatherAllOutputs/GroupByKey/Reify+PAssert$376/GroupGlobally/GatherAllOutputs/GroupByKey/Write
[INFO] 2018-03-15T02:03:50.996Z: Executing operation PAssert$376/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-15T02:03:51.083Z: Executing operation PAssert$376/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$376/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$376/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$376/GroupGlobally/RewindowActuals/Window.Assign+PAssert$376/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$376/GroupGlobally/GroupDummyAndContents/Reify+PAssert$376/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:51.421Z: (eeef3386a23764db): Executing operation PAssert$377/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-15T02:03:51.496Z: (eeef3386a2376c43): Executing operation PAssert$377/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-15T02:03:51.660Z: (5c5f5f711bf285b0): Executing operation PAssert$377/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$377/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$377/GroupGlobally/GroupDummyAndContents/Reify+PAssert$377/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:51.685Z: (eeef3386a2376ec7): Executing operation PAssert$377/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$377/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$377/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$377/GroupGlobally/RewindowActuals/Window.Assign+PAssert$377/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$377/GroupGlobally/GroupDummyAndContents/Reify+PAssert$377/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:03:52.380Z: (9f21369c37b190c3): Workers have started successfully.
[INFO] 2018-03-15T02:03:57.450Z: (eeef3386a2376caa): Executing operation PAssert$377/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-15T02:03:57.524Z: (5c5f5f711bf28c8c): Executing operation PAssert$377/GroupGlobally/GroupDummyAndContents/Read+PAssert$377/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$377/GroupGlobally/Values/Values/Map+PAssert$377/GroupGlobally/ParDo(Concat)+PAssert$377/GetPane/Map+PAssert$377/RunChecks+PAssert$377/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-15T02:03:59.845Z: (dfef2e6ba81749e2): Executing operation PAssert$376/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-15T02:03:59.916Z: (dfef2e6ba817461b): Executing operation PAssert$376/GroupGlobally/GroupDummyAndContents/Read+PAssert$376/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$376/GroupGlobally/Values/Values/Map+PAssert$376/GroupGlobally/ParDo(Concat)+PAssert$376/GetPane/Map+PAssert$376/RunChecks+PAssert$376/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-15T02:04:01.983Z: (b6198bbdcd05619a): Cleaning up.
[INFO] 2018-03-15T02:04:02.058Z: (b6198bbdcd056a0c): Stopping worker pool...
[INFO] 2018-03-15T02:04:01.984Z: (5cb1e5f1c8cd847e): Cleaning up.
[INFO] 2018-03-15T02:04:02.076Z: (5cb1e5f1c8cd8e4c): Stopping worker pool...
[INFO] 2018-03-15T02:04:06.543Z: (f223daef09ef24e6): Executing operation PAssert$374/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-15T02:04:06.641Z: (f223daef09ef26a7): Executing operation PAssert$374/GroupGlobally/GroupDummyAndContents/Read+PAssert$374/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$374/GroupGlobally/Values/Values/Map+PAssert$374/GroupGlobally/ParDo(Concat)+PAssert$374/GetPane/Map+PAssert$374/RunChecks+PAssert$374/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-15T02:04:06.755Z: (e81a6fe065846069): Executing operation PAssert$372/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-15T02:04:06.814Z: (e81a6fe06584624e): Executing operation PAssert$372/GroupGlobally/GroupDummyAndContents/Read+PAssert$372/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$372/GroupGlobally/Values/Values/Map+PAssert$372/GroupGlobally/ParDo(Concat)+PAssert$372/GetPane/Map+PAssert$372/RunChecks+PAssert$372/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-15T02:04:08.759Z: (608f4bcf24ab4c82): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-15T02:04:08.793Z: (608f4bcf24ab4c68): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-15T02:04:13.870Z: (301b33daed6f88ba): Executing operation PAssert$375/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-15T02:04:13.929Z: (301b33daed6f8b7b): Executing operation PAssert$375/GroupGlobally/GroupDummyAndContents/Read+PAssert$375/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$375/GroupGlobally/Values/Values/Map+PAssert$375/GroupGlobally/ParDo(Concat)+PAssert$375/GetPane/Map+PAssert$375/RunChecks+PAssert$375/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-15T02:04:13.968Z: (c241b1d901a6a0e6): Executing operation PAssert$373/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-15T02:04:14.027Z: (c241b1d901a6a0ad): Executing operation PAssert$373/GroupGlobally/GroupDummyAndContents/Read+PAssert$373/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$373/GroupGlobally/Values/Values/Map+PAssert$373/GroupGlobally/ParDo(Concat)+PAssert$373/GetPane/Map+PAssert$373/RunChecks+PAssert$373/VerifyAssertions/ParDo(DefaultConclude)
[INFO] Job 2018-03-14_19_01_29-10886588099875761710 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_19_01_29-10886588099875761710. Found 0 success, 0 failures out of 0 expected assertions.
[INFO] 2018-03-15T02:04:21.184Z: (2e52e37dedf2ac9a): Cleaning up.
[INFO] 2018-03-15T02:04:21.235Z: (2e52e37dedf2aa4c): Stopping worker pool...
[INFO] 2018-03-15T02:04:24.460Z: (684c161be05883bc): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-15T02:04:24.495Z: (684c161be058877e): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-15T02:04:24.534Z: (81cd215cf11ea14a): Worker pool stopped.
[INFO] 2018-03-15T02:04:24.352Z: (843ef0c8d382553a): Executing operation PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Close
[INFO] 2018-03-15T02:04:24.411Z: (843ef0c8d3825fa2): Executing operation PAssert$378/GroupGlobally/GroupDummyAndContents/Create
[INFO] 2018-03-15T02:04:24.524Z: (403306f950765d1a): Executing operation PAssert$378/GroupGlobally/Create.Values/Read(CreateSource)+PAssert$378/GroupGlobally/WindowIntoDummy/Window.Assign+PAssert$378/GroupGlobally/GroupDummyAndContents/Reify+PAssert$378/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:04:24.549Z: (843ef0c8d3825ef3): Executing operation PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/Read+PAssert$378/GroupGlobally/GatherAllOutputs/GroupByKey/GroupByWindow+PAssert$378/GroupGlobally/GatherAllOutputs/Values/Values/Map+PAssert$378/GroupGlobally/RewindowActuals/Window.Assign+PAssert$378/GroupGlobally/KeyForDummy/AddKeys/Map+PAssert$378/GroupGlobally/GroupDummyAndContents/Reify+PAssert$378/GroupGlobally/GroupDummyAndContents/Write
[INFO] 2018-03-15T02:04:26.095Z: (30f0c9fa9807c2f1): Autoscaling: Resized worker pool from 2 to 0.
[INFO] 2018-03-15T02:04:26.137Z: (30f0c9fa9807c9db): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-15T02:04:26.170Z: (6c11a49e7cb3b975): Worker pool stopped.
[INFO] Job 2018-03-14_19_01_24-4716141377124645980 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_19_01_24-4716141377124645980. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] Job 2018-03-14_19_01_14-9351954352506139960 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_19_01_14-9351954352506139960. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-15T02:04:34.274Z: (843ef0c8d3825929): Executing operation PAssert$378/GroupGlobally/GroupDummyAndContents/Close
[INFO] 2018-03-15T02:04:34.331Z: (843ef0c8d3825e44): Executing operation PAssert$378/GroupGlobally/GroupDummyAndContents/Read+PAssert$378/GroupGlobally/GroupDummyAndContents/GroupByWindow+PAssert$378/GroupGlobally/Values/Values/Map+PAssert$378/GroupGlobally/ParDo(Concat)+PAssert$378/GetPane/Map+PAssert$378/RunChecks+PAssert$378/VerifyAssertions/ParDo(DefaultConclude)
[INFO] 2018-03-15T02:04:37.570Z: (dd18d41693d46f5): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-15T02:04:37.607Z: (dd18d41693d432b): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-15T02:04:36.802Z: (b74543d965bf9df9): Cleaning up.
[INFO] 2018-03-15T02:04:36.858Z: (b74543d965bf96d3): Stopping worker pool...
[INFO] Job 2018-03-14_19_01_42-14400184224065688204 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_19_01_42-14400184224065688204. Found 0 success, 0 failures out of 0 expected assertions.
[INFO] 2018-03-15T02:04:59.889Z: (b190f8853ab9628): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-15T02:04:59.920Z: (b190f8853ab92ae): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-15T02:04:59.964Z: (6eed8dfb078344b1): Worker pool stopped.
[INFO] Job 2018-03-14_19_01_49-17127187927384441249 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_19_01_49-17127187927384441249. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-15T02:05:23.440Z: (3643a36850f7caf0): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-15T02:05:23.470Z: (3643a36850f7c8ca): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] Job 2018-03-14_19_02_31-9595242788350227598 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_19_02_31-9595242788350227598. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-15T02:05:37.189Z: (18aaef27924d5d28): Autoscaling: Resized worker pool from 3 to 0.
[INFO] 2018-03-15T02:05:37.216Z: (18aaef27924d52a2): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-15T02:05:38.628Z: (c4493787a767bcf): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-15T02:05:38.660Z: (c4493787a767e25): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] 2018-03-15T02:05:38.850Z: (2e52e37dedf2a1e4): Worker pool stopped.
[INFO] Job 2018-03-14_19_02_00-10336364376504564410 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_19_02_00-10336364376504564410. Found 4 success, 0 failures out of 4 expected assertions.
[INFO] Job 2018-03-14_19_02_07-10648072011005301662 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_19_02_07-10648072011005301662. Found 1 success, 0 failures out of 1 expected assertions.
[INFO] 2018-03-15T02:05:48.240Z: (89933a2b8509ae56): Autoscaling: Resized worker pool from 1 to 0.
[INFO] 2018-03-15T02:05:48.272Z: (89933a2b8509a4d8): Autoscaling: Would further reduce the number of workers but reached the minimum number allowed for the job.
[INFO] Job 2018-03-14_19_02_43-14812570830438480351 finished with status DONE.
[INFO] Success result for Dataflow job 2018-03-14_19_02_43-14812570830438480351. Found 1 success, 0 failures out of 1 expected assertions.
2018-03-15T02:05:57.434 [INFO] Running org.apache.beam.sdk.testing.PAssertTest
2018-03-15T02:05:57.442 [INFO] Tests run: 17, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 456.257 s - in org.apache.beam.sdk.testing.PAssertTest
2018-03-15T02:05:57.443 [INFO] Running org.apache.beam.sdk.values.PDoneTest
2018-03-15T02:05:57.446 [WARNING] Tests run: 1, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0 s - in org.apache.beam.sdk.values.PDoneTest
2018-03-15T02:05:57.446 [INFO] Running org.apache.beam.sdk.transforms.KvSwapTest
2018-03-15T02:05:57.451 [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 210.233 s - in org.apache.beam.sdk.transforms.KvSwapTest
2018-03-15T02:05:57.452 [INFO] Running org.apache.beam.sdk.transforms.windowing.WindowTest
2018-03-15T02:05:57.457 [INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 262.692 s - in org.apache.beam.sdk.transforms.windowing.WindowTest
2018-03-15T02:05:57.457 [INFO] Running org.apache.beam.sdk.transforms.ValuesTest
2018-03-15T02:05:57.463 [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 240.837 s - in org.apache.beam.sdk.transforms.ValuesTest
2018-03-15T02:05:57.464 [INFO] Running org.apache.beam.sdk.transforms.KeysTest
2018-03-15T02:05:57.468 [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 203.441 s - in org.apache.beam.sdk.transforms.KeysTest
2018-03-15T02:05:57.471 [INFO] 
2018-03-15T02:05:57.472 [INFO] Results:
2018-03-15T02:05:57.472 [INFO] 
2018-03-15T02:05:57.472 [WARNING] Tests run: 305, Failures: 0, Errors: 0, Skipped: 2
2018-03-15T02:05:57.472 [INFO] 
[JENKINS] Recording test results
2018-03-15T02:05:58.566 [INFO] 
2018-03-15T02:05:58.566 [INFO] --- maven-dependency-plugin:3.0.2:analyze-only (default) @ beam-runners-google-cloud-dataflow-java ---
2018-03-15T02:05:58.770 [INFO] No dependency problems found
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled
[JENKINS] Archiving disabled2018-03-15T02:06:13.083 [INFO] ------------------------------------------------------------------------
2018-03-15T02:06:13.083 [INFO] Reactor Summary:
2018-03-15T02:06:13.083 [INFO] 
2018-03-15T02:06:13.084 [INFO] Apache Beam :: Parent .............................. SUCCESS [ 12.035 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: SDKs :: Java :: Build Tools ......... SUCCESS [  8.682 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: Model ............................... SUCCESS [  1.988 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: Model :: Pipeline ................... SUCCESS [  8.277 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: Model :: Job Management ............. SUCCESS [  5.997 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: Model :: Fn Execution ............... SUCCESS [  6.813 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: SDKs ................................ SUCCESS [  2.203 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: SDKs :: Java ........................ SUCCESS [  1.975 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: SDKs :: Java :: Core ................ SUCCESS [02:09 min]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: SDKs :: Java :: Extensions .......... SUCCESS [  2.124 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: SDKs :: Java :: Extensions :: Google Cloud Platform Core SUCCESS [ 11.945 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: Runners ............................. SUCCESS [  2.068 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: Runners :: Core Construction Java ... SUCCESS [ 13.957 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: Runners :: Core Java ................ SUCCESS [ 32.835 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: SDKs :: Java :: IO .................. SUCCESS [  2.121 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: Runners :: Local Java Core .......... SUCCESS [  5.226 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: Runners :: Direct Java .............. SUCCESS [05:39 min]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: SDKs :: Java :: Extensions :: Protobuf SUCCESS [  7.900 s]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: SDKs :: Java :: IO :: Google Cloud Platform SUCCESS [01:57 min]
2018-03-15T02:06:13.084 [INFO] Apache Beam :: Runners :: Google Cloud Dataflow .... SUCCESS [  01:44 h]
2018-03-15T02:06:13.084 [INFO] ------------------------------------------------------------------------
2018-03-15T02:06:13.084 [INFO] BUILD SUCCESS
2018-03-15T02:06:13.084 [INFO] ------------------------------------------------------------------------
2018-03-15T02:06:13.085 [INFO] Total time: 01:57 h
2018-03-15T02:06:13.085 [INFO] Finished at: 2018-03-15T02:06:13Z

2018-03-15T02:06:14.442 [INFO] Final Memory: 330M/2592M
2018-03-15T02:06:14.442 [INFO] ------------------------------------------------------------------------
channel stopped
Not sending mail to unregistered user ehudm@google.com