Build failed in Jenkins: beam_PostCommit_Python_VR_Flink #1137

[Apache Jenkins Server <je...@builds.apache.org>]

See <https://builds.apache.org/job/beam_PostCommit_Python_VR_Flink/1137/display/redirect?page=changes>

Changes:

[ehudm] Reimplement GCS copies with rewrites.

[klk] Add toplevel :sqlPostCommit gradle command

[klk] Add Jenkins job to run :sqlPostCommit

[melissapa] [BEAM-6295] Fix versions in 2.8.0 Java dependencies table

------------------------------------------
[...truncated 4.36 MB...]
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.jobmaster.slotpool.SlotPool - Cannot serve slot request, no ResourceManager connected. Adding as pending request [SlotRequestId{9b98143b9c0bab67d217157cb808618a}]
[jobmanager-future-thread-1] INFO org.apache.flink.runtime.highavailability.nonha.embedded.EmbeddedLeaderService - Received confirmation of leadership for leader akka://flink/user/jobmanager_45 , session=aa31b423-98ba-4e88-94cd-04ae1e96c9e4
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.jobmaster.JobMaster - Connecting to ResourceManager akka://flink/user/resourcemanager_d70485e4-7005-4c38-be29-6d9caf4b1cc0(89ecf95dc75072ce6ccb214e015d4d60)
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.jobmaster.JobMaster - Resolved ResourceManager address, beginning registration
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.jobmaster.JobMaster - Registration at ResourceManager attempt 1 (timeout=100ms)
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.resourcemanager.StandaloneResourceManager - Registering job manager 94cd04ae1e96c9e4aa31b42398ba4e88@akka://flink/user/jobmanager_45 for job 063a68651b6b57486e3e440d6adec690.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.resourcemanager.StandaloneResourceManager - Registered job manager 94cd04ae1e96c9e4aa31b42398ba4e88@akka://flink/user/jobmanager_45 for job 063a68651b6b57486e3e440d6adec690.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.jobmaster.JobMaster - JobManager successfully registered at ResourceManager, leader id: 89ecf95dc75072ce6ccb214e015d4d60.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.jobmaster.slotpool.SlotPool - Requesting new slot [SlotRequestId{9b98143b9c0bab67d217157cb808618a}] and profile ResourceProfile{cpuCores=-1.0, heapMemoryInMB=-1, directMemoryInMB=0, nativeMemoryInMB=0, networkMemoryInMB=0} from resource manager.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.resourcemanager.StandaloneResourceManager - Request slot with profile ResourceProfile{cpuCores=-1.0, heapMemoryInMB=-1, directMemoryInMB=0, nativeMemoryInMB=0, networkMemoryInMB=0} for job 063a68651b6b57486e3e440d6adec690 with allocation id AllocationID{7e0d132d49fca460a6082e621f389efa}.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Receive slot request AllocationID{7e0d132d49fca460a6082e621f389efa} for job 063a68651b6b57486e3e440d6adec690 from resource manager with leader id 89ecf95dc75072ce6ccb214e015d4d60.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Allocated slot for AllocationID{7e0d132d49fca460a6082e621f389efa}.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Add job 063a68651b6b57486e3e440d6adec690 for job leader monitoring.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Try to register at job manager akka://flink/user/jobmanager_45 with leader id aa31b423-98ba-4e88-94cd-04ae1e96c9e4.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Resolved JobManager address, beginning registration
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Registration at JobManager attempt 1 (timeout=100ms)
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Successful registration at job manager akka://flink/user/jobmanager_45 for job 063a68651b6b57486e3e440d6adec690.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Establish JobManager connection for job 063a68651b6b57486e3e440d6adec690.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Offer reserved slots to the leader of job 063a68651b6b57486e3e440d6adec690.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.slot.TaskSlotTable - Activate slot AllocationID{7e0d132d49fca460a6082e621f389efa}.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (795dc6618b7a002caca0e6d47e1a26b6) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (attempt #0) to localhost
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (ffddb06ebac29214c97628da3dc15e79) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (attempt #0) to localhost
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - ToKeyedWorkItem (1/1) (c92471e8016218b4783cbd87b73f0c51) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying ToKeyedWorkItem (1/1) (attempt #0) to localhost
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (48bf66488ea0acc25236df48feaccbd0) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (attempt #0) to localhost
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task Source: Impulse -> [4]assert_that/{Create, Group} (1/1).
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (097d92e408ab18d1bd3f5fb4d3d4fd9d) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (attempt #0) to localhost
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (795dc6618b7a002caca0e6d47e1a26b6) switched from CREATED to DEPLOYING.
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak safety net for task Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (795dc6618b7a002caca0e6d47e1a26b6) [DEPLOYING]
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (85f3ce12c5eeb58d39eadd6487f1711f) switched from SCHEDULED to DEPLOYING.
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (795dc6618b7a002caca0e6d47e1a26b6) [DEPLOYING].
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (attempt #0) to localhost
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1).
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (795dc6618b7a002caca0e6d47e1a26b6) [DEPLOYING].
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (ffddb06ebac29214c97628da3dc15e79) switched from CREATED to DEPLOYING.
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak safety net for task Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (ffddb06ebac29214c97628da3dc15e79) [DEPLOYING]
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (ffddb06ebac29214c97628da3dc15e79) [DEPLOYING].
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (ffddb06ebac29214c97628da3dc15e79) [DEPLOYING].
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task ToKeyedWorkItem (1/1).
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - ToKeyedWorkItem (1/1) (c92471e8016218b4783cbd87b73f0c51) switched from CREATED to DEPLOYING.
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak safety net for task ToKeyedWorkItem (1/1) (c92471e8016218b4783cbd87b73f0c51) [DEPLOYING]
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task ToKeyedWorkItem (1/1) (c92471e8016218b4783cbd87b73f0c51) [DEPLOYING].
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: ToKeyedWorkItem (1/1) (c92471e8016218b4783cbd87b73f0c51) [DEPLOYING].
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1).
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (48bf66488ea0acc25236df48feaccbd0) switched from CREATED to DEPLOYING.
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak safety net for task Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (48bf66488ea0acc25236df48feaccbd0) [DEPLOYING]
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (48bf66488ea0acc25236df48feaccbd0) [DEPLOYING].
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (48bf66488ea0acc25236df48feaccbd0) [DEPLOYING].
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1).
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (097d92e408ab18d1bd3f5fb4d3d4fd9d) switched from CREATED to DEPLOYING.
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak safety net for task assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (097d92e408ab18d1bd3f5fb4d3d4fd9d) [DEPLOYING]
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (097d92e408ab18d1bd3f5fb4d3d4fd9d) [DEPLOYING].
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (097d92e408ab18d1bd3f5fb4d3d4fd9d) [DEPLOYING].
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (097d92e408ab18d1bd3f5fb4d3d4fd9d) switched from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (097d92e408ab18d1bd3f5fb4d3d4fd9d) switched from DEPLOYING to RUNNING.
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.streaming.runtime.tasks.StreamTask - No state backend has been configured, using default (Memory / JobManager) MemoryStateBackend (data in heap memory / checkpoints to JobManager) (checkpoints: 'null', savepoints: 'null', asynchronous: TRUE, maxStateSize: 5242880)
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - ToKeyedWorkItem (1/1) (c92471e8016218b4783cbd87b73f0c51) switched from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1).
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - ToKeyedWorkItem (1/1) (c92471e8016218b4783cbd87b73f0c51) switched from DEPLOYING to RUNNING.
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.streaming.runtime.tasks.StreamTask - No state backend has been configured, using default (Memory / JobManager) MemoryStateBackend (data in heap memory / checkpoints to JobManager) (checkpoints: 'null', savepoints: 'null', asynchronous: TRUE, maxStateSize: 5242880)
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (85f3ce12c5eeb58d39eadd6487f1711f) switched from CREATED to DEPLOYING.
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak safety net for task GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (85f3ce12c5eeb58d39eadd6487f1711f) [DEPLOYING]
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (ffddb06ebac29214c97628da3dc15e79) switched from DEPLOYING to RUNNING.
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (85f3ce12c5eeb58d39eadd6487f1711f) [DEPLOYING].
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (ffddb06ebac29214c97628da3dc15e79) switched from DEPLOYING to RUNNING.
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.streaming.runtime.tasks.StreamTask - No state backend has been configured, using default (Memory / JobManager) MemoryStateBackend (data in heap memory / checkpoints to JobManager) (checkpoints: 'null', savepoints: 'null', asynchronous: TRUE, maxStateSize: 5242880)
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (795dc6618b7a002caca0e6d47e1a26b6) switched from DEPLOYING to RUNNING.
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (85f3ce12c5eeb58d39eadd6487f1711f) [DEPLOYING].
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (795dc6618b7a002caca0e6d47e1a26b6) switched from DEPLOYING to RUNNING.
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.streaming.runtime.tasks.StreamTask - No state backend has been configured, using default (Memory / JobManager) MemoryStateBackend (data in heap memory / checkpoints to JobManager) (checkpoints: 'null', savepoints: 'null', asynchronous: TRUE, maxStateSize: 5242880)
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (48bf66488ea0acc25236df48feaccbd0) switched from DEPLOYING to RUNNING.
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.streaming.runtime.tasks.StreamTask - No state backend has been configured, using default (Memory / JobManager) MemoryStateBackend (data in heap memory / checkpoints to JobManager) (checkpoints: 'null', savepoints: 'null', asynchronous: TRUE, maxStateSize: 5242880)
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (48bf66488ea0acc25236df48feaccbd0) switched from DEPLOYING to RUNNING.
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (85f3ce12c5eeb58d39eadd6487f1711f) switched from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (85f3ce12c5eeb58d39eadd6487f1711f) switched from DEPLOYING to RUNNING.
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.streaming.runtime.tasks.StreamTask - No state backend has been configured, using default (Memory / JobManager) MemoryStateBackend (data in heap memory / checkpoints to JobManager) (checkpoints: 'null', savepoints: 'null', asynchronous: TRUE, maxStateSize: 5242880)
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.state.heap.HeapKeyedStateBackend - Initializing heap keyed state backend with stream factory.
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - The operator name [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} exceeded the 80 characters length limit and was truncated.
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.state.heap.HeapKeyedStateBackend - Initializing heap keyed state backend with stream factory.
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.state.heap.HeapKeyedStateBackend - Initializing heap keyed state backend with stream factory.
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] WARN org.apache.beam.runners.fnexecution.environment.DockerCommand - Unable to pull docker image jenkins-docker-apache.bintray.io/beam/python:latest, cause: Received exit code 1 for command 'docker pull jenkins-docker-apache.bintray.io/beam/python:latest'. stderr: Error response from daemon: unknown: Repo 'apache' was not found
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.artifact.BeamFileSystemArtifactRetrievalService - GetManifest for /tmp/flinktest4rZWuv/job_00174fb6-ba53-4ed9-af2a-8c4e38ddbc0a/MANIFEST
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.artifact.BeamFileSystemArtifactRetrievalService - Loading manifest for retrieval token /tmp/flinktest4rZWuv/job_00174fb6-ba53-4ed9-af2a-8c4e38ddbc0a/MANIFEST
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.artifact.BeamFileSystemArtifactRetrievalService - Manifest at /tmp/flinktest4rZWuv/job_00174fb6-ba53-4ed9-af2a-8c4e38ddbc0a/MANIFEST has 0 artifact locations
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.artifact.BeamFileSystemArtifactRetrievalService - GetManifest for /tmp/flinktest4rZWuv/job_00174fb6-ba53-4ed9-af2a-8c4e38ddbc0a/MANIFEST -> 0 artifacts
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.logging.GrpcLoggingService - Beam Fn Logging client connected.
[grpc-default-executor-1] INFO sdk_worker_main.main - Logging handler created.
[grpc-default-executor-1] INFO sdk_worker_main.start - Status HTTP server running at localhost:37245
[grpc-default-executor-1] INFO sdk_worker_main.main - semi_persistent_directory: /tmp
[grpc-default-executor-1] WARN sdk_worker_main._load_main_session - No session file found: /tmp/staged/pickled_main_session. Functions defined in __main__ (interactive session) may fail. 
[grpc-default-executor-1] INFO pipeline_options.get_all_options - Parsing unknown args: [u'--direct_runner_use_stacked_bundle', u'--options_id=23', u'--pipeline_type_check', u'--sdk_worker_parallelism=1']
[grpc-default-executor-1] INFO sdk_worker_main.main - Python sdk harness started with pipeline_options: {'runner': u'None', 'streaming': True, 'experiments': [u'beam_fn_api'], 'environment_type': u'DOCKER', 'sdk_location': u'container', 'flink_master': '[local]', 'direct_runner_use_stacked_bundle': None, 'sdk_worker_parallelism': u'1', 'job_name': u'test_windowing_1545426298.46', 'options_id': u'23', 'pipeline_type_check': None, 'parallelism': 1, 'job_endpoint': u'localhost:52125'}
[grpc-default-executor-1] INFO sdk_worker.__init__ - Creating insecure control channel for localhost:40031.
[grpc-default-executor-1] INFO sdk_worker.__init__ - Control channel established.
[grpc-default-executor-1] INFO sdk_worker.__init__ - Initializing SDKHarness with 12 workers.
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.control.FnApiControlClientPoolService - Beam Fn Control client connected with id 1
[grpc-default-executor-1] INFO sdk_worker.create_state_handler - Creating insecure state channel for localhost:36319
[grpc-default-executor-1] INFO sdk_worker.create_state_handler - State channel established.
[grpc-default-executor-1] WARN bundle_processor.create_operation - No unique name set for transform fn/write/ref_PCollection_PCollection_4:0 
[grpc-default-executor-1] WARN bundle_processor.create_operation - No unique name set for transform fn/write/ref_PCollection_PCollection_24:1:0 
[grpc-default-executor-1] INFO data_plane.create_data_channel - Creating channel for localhost:46739
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.data.GrpcDataService - Beam Fn Data client connected.
[grpc-default-executor-1] WARN bundle_processor.create_operation - No unique name set for transform fn/read/ref_PCollection_PCollection_17:0 
[grpc-default-executor-1] WARN bundle_processor.create_operation - No unique name set for transform fn/read/ref_PCollection_PCollection_1:0 
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:process_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [4]assert_that/{Create, Group}, 0]
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:start_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [4]assert_that/{Create, Group}, 0]
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:element_count:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [4]assert_that/{Create, Group}, 0]
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:ptransform_execution_time:total_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [4]assert_that/{Create, Group}, 0]
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:finish_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [4]assert_that/{Create, Group}, 0]
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:start_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle}, 0]
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:process_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle}, 0]
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:ptransform_execution_time:total_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle}, 0]
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:element_count:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle}, 0]
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:finish_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle}, 0]
[grpc-default-executor-1] WARN bundle_processor.create_operation - No unique name set for transform fn/write/ref_PCollection_PCollection_12:0 
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (ffddb06ebac29214c97628da3dc15e79) switched from RUNNING to FINISHED.
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (ffddb06ebac29214c97628da3dc15e79).
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are closed for task Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (ffddb06ebac29214c97628da3dc15e79) [FINISHED]
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and sending final execution state FINISHED to JobManager for task Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem ffddb06ebac29214c97628da3dc15e79.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (ffddb06ebac29214c97628da3dc15e79) switched from RUNNING to FINISHED.
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (795dc6618b7a002caca0e6d47e1a26b6) switched from RUNNING to FINISHED.
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (795dc6618b7a002caca0e6d47e1a26b6).
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are closed for task Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (795dc6618b7a002caca0e6d47e1a26b6) [FINISHED]
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and sending final execution state FINISHED to JobManager for task Source: Impulse -> [4]assert_that/{Create, Group} 795dc6618b7a002caca0e6d47e1a26b6.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (795dc6618b7a002caca0e6d47e1a26b6) switched from RUNNING to FINISHED.
[grpc-default-executor-1] WARN bundle_processor.create_operation - No unique name set for transform fn/read/ref_PCollection_PCollection_7:0 
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:process_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn), 0]
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:start_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn), 0]
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:finish_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn), 0]
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:element_count:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn), 0]
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:ptransform_execution_time:total_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn), 0]
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (48bf66488ea0acc25236df48feaccbd0) switched from RUNNING to FINISHED.
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (48bf66488ea0acc25236df48feaccbd0).
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are closed for task Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (48bf66488ea0acc25236df48feaccbd0) [FINISHED]
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and sending final execution state FINISHED to JobManager for task Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem 48bf66488ea0acc25236df48feaccbd0.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (48bf66488ea0acc25236df48feaccbd0) switched from RUNNING to FINISHED.
[grpc-default-executor-1] WARN bundle_processor.create_operation - No unique name set for transform fn/write/ref_PCollection_PCollection_24:0:0 
[grpc-default-executor-1] WARN bundle_processor.create_operation - No unique name set for transform fn/read/ref_PCollection_PCollection_15:0 
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:process_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that}, 0]
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:start_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that}, 0]
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:finish_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that}, 0]
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:element_count:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that}, 0]
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:ptransform_execution_time:total_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that}, 0]
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (85f3ce12c5eeb58d39eadd6487f1711f) switched from RUNNING to FINISHED.
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (85f3ce12c5eeb58d39eadd6487f1711f).
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are closed for task GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (85f3ce12c5eeb58d39eadd6487f1711f) [FINISHED]
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and sending final execution state FINISHED to JobManager for task GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} 85f3ce12c5eeb58d39eadd6487f1711f.
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - ToKeyedWorkItem (1/1) (c92471e8016218b4783cbd87b73f0c51) switched from RUNNING to FINISHED.
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for ToKeyedWorkItem (1/1) (c92471e8016218b4783cbd87b73f0c51).
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are closed for task ToKeyedWorkItem (1/1) (c92471e8016218b4783cbd87b73f0c51) [FINISHED]
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and sending final execution state FINISHED to JobManager for task ToKeyedWorkItem c92471e8016218b4783cbd87b73f0c51.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (85f3ce12c5eeb58d39eadd6487f1711f) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - ToKeyedWorkItem (1/1) (c92471e8016218b4783cbd87b73f0c51) switched from RUNNING to FINISHED.
[grpc-default-executor-1] WARN bundle_processor.create_operation - No unique name set for transform fn/read/ref_PCollection_PCollection_27:0 
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:process_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [3]assert_that/{Group, Unkey, Match}, 0]
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:start_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [3]assert_that/{Group, Unkey, Match}, 0]
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:element_count:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [3]assert_that/{Group, Unkey, Match}, 0]
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:ptransform_execution_time:total_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [3]assert_that/{Group, Unkey, Match}, 0]
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:finish_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, e5e15394-ee1d-4062-8a92-c8d9a75775a8, test_windowing_1545426298.46, [3]assert_that/{Group, Unkey, Match}, 0]
[grpc-default-executor-1] INFO sdk_worker.run - No more requests from control plane
[grpc-default-executor-1] INFO sdk_worker.run - SDK Harness waiting for in-flight requests to complete
[grpc-default-executor-1] WARN org.apache.beam.sdk.fn.data.BeamFnDataGrpcMultiplexer - Hanged up for unknown endpoint.
[grpc-default-executor-2] INFO data_plane.close - Closing all cached grpc data channels.
[grpc-default-executor-2] INFO sdk_worker.close - Closing all cached gRPC state handlers.
[grpc-default-executor-2] INFO sdk_worker.run - Done consuming work.
[grpc-default-executor-2] INFO sdk_worker_main.main - Python sdk harness exiting.
[grpc-default-executor-2] INFO org.apache.beam.runners.fnexecution.logging.GrpcLoggingService - Logging client hanged up.
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] WARN org.apache.beam.sdk.fn.data.BeamFnDataGrpcMultiplexer - Hanged up for unknown endpoint.
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (097d92e408ab18d1bd3f5fb4d3d4fd9d) switched from RUNNING to FINISHED.
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (097d92e408ab18d1bd3f5fb4d3d4fd9d).
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are closed for task assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (097d92e408ab18d1bd3f5fb4d3d4fd9d) [FINISHED]
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and sending final execution state FINISHED to JobManager for task assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} 097d92e408ab18d1bd3f5fb4d3d4fd9d.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (097d92e408ab18d1bd3f5fb4d3d4fd9d) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Job test_windowing_1545426298.46 (063a68651b6b57486e3e440d6adec690) switched from state RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.checkpoint.CheckpointCoordinator - Stopping checkpoint coordinator for job 063a68651b6b57486e3e440d6adec690.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.checkpoint.StandaloneCompletedCheckpointStore - Shutting down
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.dispatcher.StandaloneDispatcher - Job 063a68651b6b57486e3e440d6adec690 reached globally terminal state FINISHED.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.jobmaster.JobMaster - Stopping the JobMaster for job test_windowing_1545426298.46(063a68651b6b57486e3e440d6adec690).
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Close JobManager connection for job 063a68651b6b57486e3e440d6adec690.
[flink-runner-job-server] INFO org.apache.flink.runtime.minicluster.MiniCluster - Shutting down Flink Mini Cluster
[flink-runner-job-server] INFO org.apache.flink.runtime.dispatcher.DispatcherRestEndpoint - Shutting down rest endpoint.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.jobmaster.JobMaster - Close ResourceManager connection 11cc61dd8b5a20c39db8af4b9f02dcea: JobManager is shutting down..
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.jobmaster.slotpool.SlotPool - Suspending SlotPool.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.dispatcher.StandaloneDispatcher - Stopping dispatcher akka://flink/user/dispatcher0c0ad7c4-ce0a-45ab-b8b2-5e8f87d4d9b6.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.dispatcher.StandaloneDispatcher - Stopping all currently running jobs of dispatcher akka://flink/user/dispatcher0c0ad7c4-ce0a-45ab-b8b2-5e8f87d4d9b6.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.jobmaster.slotpool.SlotPool - Stopping SlotPool.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.slot.TaskSlotTable - Free slot TaskSlot(index:0, state:ALLOCATED, resource profile: ResourceProfile{cpuCores=1.0, heapMemoryInMB=42, directMemoryInMB=0, nativeMemoryInMB=0, networkMemoryInMB=0}, allocationId: AllocationID{7e0d132d49fca460a6082e621f389efa}, jobId: 063a68651b6b57486e3e440d6adec690).
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Remove job 063a68651b6b57486e3e440d6adec690 from job leader monitoring.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Close JobManager connection for job 063a68651b6b57486e3e440d6adec690.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.resourcemanager.StandaloneResourceManager - Disconnect job manager 94cd04ae1e96c9e4aa31b42398ba4e88@akka://flink/user/jobmanager_45 for job 063a68651b6b57486e3e440d6adec690 from the resource manager.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Stopping TaskExecutor akka://flink/user/taskmanager_44.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.state.TaskExecutorLocalStateStoresManager - Shutting down TaskExecutorLocalStateStoresManager.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.resourcemanager.slotmanager.SlotManager - Closing the SlotManager.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.resourcemanager.slotmanager.SlotManager - Suspending the SlotManager.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.rest.handler.legacy.backpressure.StackTraceSampleCoordinator - Shutting down stack trace sample coordinator.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.io.disk.iomanager.IOManager - I/O manager removed spill file directory /tmp/flink-io-2c540fcc-ba68-4006-94a9-ebafda55bfae
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.io.network.NetworkEnvironment - Shutting down the network environment and its components.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.dispatcher.StandaloneDispatcher - Stopped dispatcher akka://flink/user/dispatcher0c0ad7c4-ce0a-45ab-b8b2-5e8f87d4d9b6.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Stop job leader service.
[ForkJoinPool.commonPool-worker-9] INFO org.apache.flink.runtime.dispatcher.DispatcherRestEndpoint - Removing cache directory /tmp/flink-web-ui
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Stopped TaskExecutor akka://flink/user/taskmanager_44.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.beam.runners.flink.metrics.FileReporter - wrote metrics to /tmp/flinktest-confzNuM7k/test-metrics.txt
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.rpc.akka.AkkaRpcService - Stopping Akka RPC service.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.blob.PermanentBlobCache - Shutting down BLOB cache
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.blob.TransientBlobCache - Shutting down BLOB cache
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.blob.BlobServer - Stopped BLOB server at 0.0.0.0:44489
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.rpc.akka.AkkaRpcService - Stopped Akka RPC service.
[flink-runner-job-server] INFO org.apache.beam.runners.flink.FlinkRunner - Execution finished in 2562 msecs
[flink-runner-job-server] INFO org.apache.beam.runners.flink.FlinkRunner - Final accumulator values:
[flink-runner-job-server] INFO org.apache.beam.runners.flink.FlinkRunner - __metricscontainers : org.apache.beam.runners.core.metrics.MetricsContainerStepMap@ae724cd3
INFO:root:Job state changed to DONE
.INFO:root:removing conf dir: /tmp/flinktest-confzNuM7k

----------------------------------------------------------------------
Ran 27 tests in 117.694s

OK (skipped=6)
:beam-sdks-python:flinkCompatibilityMatrixStreamingDOCKER (Thread[Task worker for ':' Thread 10,5,main]) completed. Took 2 mins 11.485 secs.

FAILURE: Build failed with an exception.

* Where:
Build file '<https://builds.apache.org/job/beam_PostCommit_Python_VR_Flink/ws/src/sdks/python/build.gradle'> line: 347

* What went wrong:
Execution failed for task ':beam-sdks-python:flinkCompatibilityMatrixBatchDOCKER'.
> Process 'command 'sh'' finished with non-zero exit value 1

* Try:
Run with --stacktrace option to get the stack trace. Run with --debug option to get more log output. Run with --scan to get full insights.

* Get more help at https://help.gradle.org

Deprecated Gradle features were used in this build, making it incompatible with Gradle 5.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/4.10.2/userguide/command_line_interface.html#sec:command_line_warnings

BUILD FAILED in 17m 34s
61 actionable tasks: 56 executed, 4 from cache, 1 up-to-date

Publishing build scan...
https://gradle.com/s/zwyqjgfslifa4

Build step 'Invoke Gradle script' changed build result to FAILURE
Build step 'Invoke Gradle script' marked build as failure

---------------------------------------------------------------------
To unsubscribe, e-mail: builds-unsubscribe@beam.apache.org
For additional commands, e-mail: builds-help@beam.apache.org

Jenkins build is back to normal : beam_PostCommit_Python_VR_Flink #1139

[Apache Jenkins Server <je...@builds.apache.org>]

See <https://builds.apache.org/job/beam_PostCommit_Python_VR_Flink/1139/display/redirect>


---------------------------------------------------------------------
To unsubscribe, e-mail: builds-unsubscribe@beam.apache.org
For additional commands, e-mail: builds-help@beam.apache.org

Build failed in Jenkins: beam_PostCommit_Python_VR_Flink #1138

[Apache Jenkins Server <je...@builds.apache.org>]

See <https://builds.apache.org/job/beam_PostCommit_Python_VR_Flink/1138/display/redirect?page=changes>

Changes:

[melissapa] [BEAM-6295] Fix versions in 2.7.0 Java dependencies table

------------------------------------------
[...truncated 4.33 MB...]
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (41b99ef894f2dd5f8c84f67d2fa34f72) switched from CREATED to SCHEDULED.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.jobmaster.JobMaster - Connecting to ResourceManager akka://flink/user/resourcemanager_901c94ad-b64f-488b-8cf3-25a91706cfcf(be5f0f0bd5aba6e843dda7c846f24918)
[jobmanager-future-thread-1] INFO org.apache.flink.runtime.highavailability.nonha.embedded.EmbeddedLeaderService - Received confirmation of leadership for leader akka://flink/user/jobmanager_45 , session=7badb255-2b9e-4d32-a441-355103a08d1a
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.jobmaster.JobMaster - Resolved ResourceManager address, beginning registration
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.jobmaster.JobMaster - Registration at ResourceManager attempt 1 (timeout=100ms)
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.resourcemanager.StandaloneResourceManager - Registering job manager a441355103a08d1a7badb2552b9e4d32@akka://flink/user/jobmanager_45 for job cf0ab9f2675a2b72a8b13d477ce46f79.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.resourcemanager.StandaloneResourceManager - Registered job manager a441355103a08d1a7badb2552b9e4d32@akka://flink/user/jobmanager_45 for job cf0ab9f2675a2b72a8b13d477ce46f79.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.jobmaster.JobMaster - JobManager successfully registered at ResourceManager, leader id: be5f0f0bd5aba6e843dda7c846f24918.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.jobmaster.slotpool.SlotPool - Requesting new slot [SlotRequestId{f72bf8822b7ed619207d129f9e9432ec}] and profile ResourceProfile{cpuCores=-1.0, heapMemoryInMB=-1, directMemoryInMB=0, nativeMemoryInMB=0, networkMemoryInMB=0} from resource manager.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.resourcemanager.StandaloneResourceManager - Request slot with profile ResourceProfile{cpuCores=-1.0, heapMemoryInMB=-1, directMemoryInMB=0, nativeMemoryInMB=0, networkMemoryInMB=0} for job cf0ab9f2675a2b72a8b13d477ce46f79 with allocation id AllocationID{99f39cac3d82873121599735025661ab}.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Receive slot request AllocationID{99f39cac3d82873121599735025661ab} for job cf0ab9f2675a2b72a8b13d477ce46f79 from resource manager with leader id be5f0f0bd5aba6e843dda7c846f24918.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Allocated slot for AllocationID{99f39cac3d82873121599735025661ab}.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Add job cf0ab9f2675a2b72a8b13d477ce46f79 for job leader monitoring.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Try to register at job manager akka://flink/user/jobmanager_45 with leader id 7badb255-2b9e-4d32-a441-355103a08d1a.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Resolved JobManager address, beginning registration
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Registration at JobManager attempt 1 (timeout=100ms)
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Successful registration at job manager akka://flink/user/jobmanager_45 for job cf0ab9f2675a2b72a8b13d477ce46f79.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Establish JobManager connection for job cf0ab9f2675a2b72a8b13d477ce46f79.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Offer reserved slots to the leader of job cf0ab9f2675a2b72a8b13d477ce46f79.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.taskexecutor.slot.TaskSlotTable - Activate slot AllocationID{99f39cac3d82873121599735025661ab}.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (87d887e2873709a46c2aeef24f6f6dd8) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (attempt #0) to localhost
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (c0bba6bc7477d1c2c45634250476dd94) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (attempt #0) to localhost
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (7ae8112b56347808ed3f3a07a7115b4c) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (attempt #0) to localhost
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (3950a99a67d8c8260225a8fd2b96f72f) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (attempt #0) to localhost
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1).
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - ToKeyedWorkItem (1/1) (d950451d1f187a46b4da80e8e067bff2) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying ToKeyedWorkItem (1/1) (attempt #0) to localhost
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (87d887e2873709a46c2aeef24f6f6dd8) switched from CREATED to DEPLOYING.
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak safety net for task Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (87d887e2873709a46c2aeef24f6f6dd8) [DEPLOYING]
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (41b99ef894f2dd5f8c84f67d2fa34f72) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (attempt #0) to localhost
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task Source: Impulse -> [4]assert_that/{Create, Group} (1/1).
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (87d887e2873709a46c2aeef24f6f6dd8) [DEPLOYING].
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (c0bba6bc7477d1c2c45634250476dd94) switched from CREATED to DEPLOYING.
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak safety net for task Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (c0bba6bc7477d1c2c45634250476dd94) [DEPLOYING]
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (c0bba6bc7477d1c2c45634250476dd94) [DEPLOYING].
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (87d887e2873709a46c2aeef24f6f6dd8) [DEPLOYING].
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (c0bba6bc7477d1c2c45634250476dd94) [DEPLOYING].
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1).
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (7ae8112b56347808ed3f3a07a7115b4c) switched from CREATED to DEPLOYING.
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak safety net for task Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (7ae8112b56347808ed3f3a07a7115b4c) [DEPLOYING]
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (7ae8112b56347808ed3f3a07a7115b4c) [DEPLOYING].
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (7ae8112b56347808ed3f3a07a7115b4c) [DEPLOYING].
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1).
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (3950a99a67d8c8260225a8fd2b96f72f) switched from CREATED to DEPLOYING.
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak safety net for task GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (3950a99a67d8c8260225a8fd2b96f72f) [DEPLOYING]
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (3950a99a67d8c8260225a8fd2b96f72f) [DEPLOYING].
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (3950a99a67d8c8260225a8fd2b96f72f) [DEPLOYING].
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (87d887e2873709a46c2aeef24f6f6dd8) switched from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (87d887e2873709a46c2aeef24f6f6dd8) switched from DEPLOYING to RUNNING.
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.streaming.runtime.tasks.StreamTask - No state backend has been configured, using default (Memory / JobManager) MemoryStateBackend (data in heap memory / checkpoints to JobManager) (checkpoints: 'null', savepoints: 'null', asynchronous: TRUE, maxStateSize: 5242880)
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (3950a99a67d8c8260225a8fd2b96f72f) switched from DEPLOYING to RUNNING.
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.streaming.runtime.tasks.StreamTask - No state backend has been configured, using default (Memory / JobManager) MemoryStateBackend (data in heap memory / checkpoints to JobManager) (checkpoints: 'null', savepoints: 'null', asynchronous: TRUE, maxStateSize: 5242880)
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (3950a99a67d8c8260225a8fd2b96f72f) switched from DEPLOYING to RUNNING.
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (7ae8112b56347808ed3f3a07a7115b4c) switched from DEPLOYING to RUNNING.
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.streaming.runtime.tasks.StreamTask - No state backend has been configured, using default (Memory / JobManager) MemoryStateBackend (data in heap memory / checkpoints to JobManager) (checkpoints: 'null', savepoints: 'null', asynchronous: TRUE, maxStateSize: 5242880)
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (7ae8112b56347808ed3f3a07a7115b4c) switched from DEPLOYING to RUNNING.
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (c0bba6bc7477d1c2c45634250476dd94) switched from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task ToKeyedWorkItem (1/1).
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (c0bba6bc7477d1c2c45634250476dd94) switched from DEPLOYING to RUNNING.
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - ToKeyedWorkItem (1/1) (d950451d1f187a46b4da80e8e067bff2) switched from CREATED to DEPLOYING.
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak safety net for task ToKeyedWorkItem (1/1) (d950451d1f187a46b4da80e8e067bff2) [DEPLOYING]
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.streaming.runtime.tasks.StreamTask - No state backend has been configured, using default (Memory / JobManager) MemoryStateBackend (data in heap memory / checkpoints to JobManager) (checkpoints: 'null', savepoints: 'null', asynchronous: TRUE, maxStateSize: 5242880)
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task ToKeyedWorkItem (1/1) (d950451d1f187a46b4da80e8e067bff2) [DEPLOYING].
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: ToKeyedWorkItem (1/1) (d950451d1f187a46b4da80e8e067bff2) [DEPLOYING].
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1).
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (41b99ef894f2dd5f8c84f67d2fa34f72) switched from CREATED to DEPLOYING.
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak safety net for task assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (41b99ef894f2dd5f8c84f67d2fa34f72) [DEPLOYING]
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (41b99ef894f2dd5f8c84f67d2fa34f72) [DEPLOYING].
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (41b99ef894f2dd5f8c84f67d2fa34f72) [DEPLOYING].
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (41b99ef894f2dd5f8c84f67d2fa34f72) switched from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (41b99ef894f2dd5f8c84f67d2fa34f72) switched from DEPLOYING to RUNNING.
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.streaming.runtime.tasks.StreamTask - No state backend has been configured, using default (Memory / JobManager) MemoryStateBackend (data in heap memory / checkpoints to JobManager) (checkpoints: 'null', savepoints: 'null', asynchronous: TRUE, maxStateSize: 5242880)
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - ToKeyedWorkItem (1/1) (d950451d1f187a46b4da80e8e067bff2) switched from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - ToKeyedWorkItem (1/1) (d950451d1f187a46b4da80e8e067bff2) switched from DEPLOYING to RUNNING.
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.streaming.runtime.tasks.StreamTask - No state backend has been configured, using default (Memory / JobManager) MemoryStateBackend (data in heap memory / checkpoints to JobManager) (checkpoints: 'null', savepoints: 'null', asynchronous: TRUE, maxStateSize: 5242880)
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.state.heap.HeapKeyedStateBackend - Initializing heap keyed state backend with stream factory.
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - The operator name [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} exceeded the 80 characters length limit and was truncated.
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.state.heap.HeapKeyedStateBackend - Initializing heap keyed state backend with stream factory.
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.state.heap.HeapKeyedStateBackend - Initializing heap keyed state backend with stream factory.
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] WARN org.apache.beam.runners.fnexecution.environment.DockerCommand - Unable to pull docker image jenkins-docker-apache.bintray.io/beam/python:latest, cause: Received exit code 1 for command 'docker pull jenkins-docker-apache.bintray.io/beam/python:latest'. stderr: Error response from daemon: unknown: Repo 'apache' was not found
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.artifact.BeamFileSystemArtifactRetrievalService - GetManifest for /tmp/flinktest4uu0GU/job_f5159461-d0a0-4a8f-a8fc-08722cf0b003/MANIFEST
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.artifact.BeamFileSystemArtifactRetrievalService - Loading manifest for retrieval token /tmp/flinktest4uu0GU/job_f5159461-d0a0-4a8f-a8fc-08722cf0b003/MANIFEST
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.artifact.BeamFileSystemArtifactRetrievalService - Manifest at /tmp/flinktest4uu0GU/job_f5159461-d0a0-4a8f-a8fc-08722cf0b003/MANIFEST has 0 artifact locations
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.artifact.BeamFileSystemArtifactRetrievalService - GetManifest for /tmp/flinktest4uu0GU/job_f5159461-d0a0-4a8f-a8fc-08722cf0b003/MANIFEST -> 0 artifacts
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.logging.GrpcLoggingService - Beam Fn Logging client connected.
[grpc-default-executor-1] INFO sdk_worker_main.main - Logging handler created.
[grpc-default-executor-1] INFO sdk_worker_main.start - Status HTTP server running at localhost:40715
[grpc-default-executor-1] INFO sdk_worker_main.main - semi_persistent_directory: /tmp
[grpc-default-executor-1] WARN sdk_worker_main._load_main_session - No session file found: /tmp/staged/pickled_main_session. Functions defined in __main__ (interactive session) may fail. 
[grpc-default-executor-1] INFO pipeline_options.get_all_options - Parsing unknown args: [u'--direct_runner_use_stacked_bundle', u'--options_id=23', u'--pipeline_type_check', u'--sdk_worker_parallelism=1']
[grpc-default-executor-1] INFO sdk_worker_main.main - Python sdk harness started with pipeline_options: {'runner': u'None', 'streaming': True, 'experiments': [u'beam_fn_api'], 'environment_type': u'DOCKER', 'sdk_location': u'container', 'flink_master': '[local]', 'direct_runner_use_stacked_bundle': None, 'sdk_worker_parallelism': u'1', 'job_name': u'test_windowing_1545427235.68', 'options_id': u'23', 'pipeline_type_check': None, 'parallelism': 1, 'job_endpoint': u'localhost:47727'}
[grpc-default-executor-1] INFO sdk_worker.__init__ - Creating insecure control channel for localhost:40391.
[grpc-default-executor-1] INFO sdk_worker.__init__ - Control channel established.
[grpc-default-executor-1] INFO sdk_worker.__init__ - Initializing SDKHarness with 12 workers.
[grpc-default-executor-1] INFO org.apache.beam.runners.fnexecution.control.FnApiControlClientPoolService - Beam Fn Control client connected with id 1
[grpc-default-executor-1] INFO sdk_worker.create_state_handler - Creating insecure state channel for localhost:35381
[grpc-default-executor-1] INFO sdk_worker.create_state_handler - State channel established.
[grpc-default-executor-1] WARN bundle_processor.create_operation - No unique name set for transform fn/write/ref_PCollection_PCollection_4:0 
[grpc-default-executor-1] INFO data_plane.create_data_channel - Creating channel for localhost:41295
[grpc-default-executor-1] WARN bundle_processor.create_operation - No unique name set for transform fn/write/ref_PCollection_PCollection_24:1:0 
[grpc-default-executor-2] INFO org.apache.beam.runners.fnexecution.data.GrpcDataService - Beam Fn Data client connected.
[grpc-default-executor-2] WARN bundle_processor.create_operation - No unique name set for transform fn/read/ref_PCollection_PCollection_17:0 
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:process_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [4]assert_that/{Create, Group}, 0]
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:start_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [4]assert_that/{Create, Group}, 0]
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:element_count:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [4]assert_that/{Create, Group}, 0]
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:ptransform_execution_time:total_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [4]assert_that/{Create, Group}, 0]
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:finish_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [4]assert_that/{Create, Group}, 0]
[grpc-default-executor-2] WARN bundle_processor.create_operation - No unique name set for transform fn/read/ref_PCollection_PCollection_1:0 
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:start_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle}, 0]
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:process_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle}, 0]
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:ptransform_execution_time:total_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle}, 0]
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:element_count:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle}, 0]
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:finish_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle}, 0]
[grpc-default-executor-2] WARN bundle_processor.create_operation - No unique name set for transform fn/write/ref_PCollection_PCollection_12:0 
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (c0bba6bc7477d1c2c45634250476dd94) switched from RUNNING to FINISHED.
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (c0bba6bc7477d1c2c45634250476dd94).
[Source: Impulse -> [4]assert_that/{Create, Group} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are closed for task Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (c0bba6bc7477d1c2c45634250476dd94) [FINISHED]
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and sending final execution state FINISHED to JobManager for task Source: Impulse -> [4]assert_that/{Create, Group} c0bba6bc7477d1c2c45634250476dd94.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Source: Impulse -> [4]assert_that/{Create, Group} (1/1) (c0bba6bc7477d1c2c45634250476dd94) switched from RUNNING to FINISHED.
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (87d887e2873709a46c2aeef24f6f6dd8) switched from RUNNING to FINISHED.
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (87d887e2873709a46c2aeef24f6f6dd8).
[Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are closed for task Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (87d887e2873709a46c2aeef24f6f6dd8) [FINISHED]
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and sending final execution state FINISHED to JobManager for task Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem 87d887e2873709a46c2aeef24f6f6dd8.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Source: Impulse -> [3]Create/{FlatMap(<lambda at core.py:2029>), MaybeReshuffle} -> ToKeyedWorkItem (1/1) (87d887e2873709a46c2aeef24f6f6dd8) switched from RUNNING to FINISHED.
[grpc-default-executor-2] WARN bundle_processor.create_operation - No unique name set for transform fn/read/ref_PCollection_PCollection_7:0 
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:process_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn), 0]
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:start_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn), 0]
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:finish_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn), 0]
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:element_count:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn), 0]
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:ptransform_execution_time:total_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn), 0]
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (7ae8112b56347808ed3f3a07a7115b4c) switched from RUNNING to FINISHED.
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (7ae8112b56347808ed3f3a07a7115b4c).
[Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are closed for task Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (7ae8112b56347808ed3f3a07a7115b4c) [FINISHED]
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and sending final execution state FINISHED to JobManager for task Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem 7ae8112b56347808ed3f3a07a7115b4c.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Create/MaybeReshuffle/Reshuffle/ReshufflePerKey/GroupByKey -> [5]{Create, Map(<lambda at fn_api_runner_test.py:405>), WindowInto(WindowIntoFn)} -> ToKeyedWorkItem (1/1) (7ae8112b56347808ed3f3a07a7115b4c) switched from RUNNING to FINISHED.
[grpc-default-executor-2] WARN bundle_processor.create_operation - No unique name set for transform fn/write/ref_PCollection_PCollection_24:0:0 
[grpc-default-executor-2] WARN bundle_processor.create_operation - No unique name set for transform fn/read/ref_PCollection_PCollection_15:0 
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:process_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that}, 0]
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:start_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that}, 0]
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:finish_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that}, 0]
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:element_count:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that}, 0]
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:ptransform_execution_time:total_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that}, 0]
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (3950a99a67d8c8260225a8fd2b96f72f) switched from RUNNING to FINISHED.
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (3950a99a67d8c8260225a8fd2b96f72f).
[GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are closed for task GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (3950a99a67d8c8260225a8fd2b96f72f) [FINISHED]
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and sending final execution state FINISHED to JobManager for task GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} 3950a99a67d8c8260225a8fd2b96f72f.
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - ToKeyedWorkItem (1/1) (d950451d1f187a46b4da80e8e067bff2) switched from RUNNING to FINISHED.
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for ToKeyedWorkItem (1/1) (d950451d1f187a46b4da80e8e067bff2).
[ToKeyedWorkItem (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are closed for task ToKeyedWorkItem (1/1) (d950451d1f187a46b4da80e8e067bff2) [FINISHED]
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and sending final execution state FINISHED to JobManager for task ToKeyedWorkItem d950451d1f187a46b4da80e8e067bff2.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupByKey -> [5]{Map(<lambda at fn_api_runner_test.py:408>), assert_that} (1/1) (3950a99a67d8c8260225a8fd2b96f72f) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - ToKeyedWorkItem (1/1) (d950451d1f187a46b4da80e8e067bff2) switched from RUNNING to FINISHED.
[grpc-default-executor-2] WARN bundle_processor.create_operation - No unique name set for transform fn/read/ref_PCollection_PCollection_27:0 
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:process_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [3]assert_that/{Group, Unkey, Match}, 0]
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:start_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [3]assert_that/{Group, Unkey, Match}, 0]
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:element_count:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [3]assert_that/{Group, Unkey, Match}, 0]
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:ptransform_execution_time:total_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [3]assert_that/{Group, Unkey, Match}, 0]
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] WARN org.apache.flink.metrics.MetricGroup - Name collision: Group already contains a Metric with the name 'beam.metric:pardo_execution_time:finish_bundle_msecs:v1'. Metric will not be reported.[localhost, taskmanager, ff9c902f-741c-4742-bed6-15c75aaf1034, test_windowing_1545427235.68, [3]assert_that/{Group, Unkey, Match}, 0]
[grpc-default-executor-2] INFO sdk_worker.run - No more requests from control plane
[grpc-default-executor-2] INFO sdk_worker.run - SDK Harness waiting for in-flight requests to complete
[grpc-default-executor-2] INFO data_plane.close - Closing all cached grpc data channels.
[grpc-default-executor-2] WARN org.apache.beam.sdk.fn.data.BeamFnDataGrpcMultiplexer - Hanged up for unknown endpoint.
[grpc-default-executor-2] INFO sdk_worker.close - Closing all cached gRPC state handlers.
[grpc-default-executor-2] INFO sdk_worker.run - Done consuming work.
[grpc-default-executor-2] INFO sdk_worker_main.main - Python sdk harness exiting.
[grpc-default-executor-2] INFO org.apache.beam.runners.fnexecution.logging.GrpcLoggingService - Logging client hanged up.
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] WARN org.apache.beam.sdk.fn.data.BeamFnDataGrpcMultiplexer - Hanged up for unknown endpoint.
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (41b99ef894f2dd5f8c84f67d2fa34f72) switched from RUNNING to FINISHED.
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (41b99ef894f2dd5f8c84f67d2fa34f72).
[assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are closed for task assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (41b99ef894f2dd5f8c84f67d2fa34f72) [FINISHED]
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and sending final execution state FINISHED to JobManager for task assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} 41b99ef894f2dd5f8c84f67d2fa34f72.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - assert_that/Group/GroupByKey -> [3]assert_that/{Group, Unkey, Match} (1/1) (41b99ef894f2dd5f8c84f67d2fa34f72) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.executiongraph.ExecutionGraph - Job test_windowing_1545427235.68 (cf0ab9f2675a2b72a8b13d477ce46f79) switched from state RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.checkpoint.CheckpointCoordinator - Stopping checkpoint coordinator for job cf0ab9f2675a2b72a8b13d477ce46f79.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.checkpoint.StandaloneCompletedCheckpointStore - Shutting down
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.dispatcher.StandaloneDispatcher - Job cf0ab9f2675a2b72a8b13d477ce46f79 reached globally terminal state FINISHED.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.jobmaster.JobMaster - Stopping the JobMaster for job test_windowing_1545427235.68(cf0ab9f2675a2b72a8b13d477ce46f79).
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Close JobManager connection for job cf0ab9f2675a2b72a8b13d477ce46f79.
[flink-runner-job-server] INFO org.apache.flink.runtime.minicluster.MiniCluster - Shutting down Flink Mini Cluster
[flink-runner-job-server] INFO org.apache.flink.runtime.dispatcher.DispatcherRestEndpoint - Shutting down rest endpoint.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.dispatcher.StandaloneDispatcher - Stopping dispatcher akka://flink/user/dispatcher1312ef65-a535-41c4-9b0c-d207b89eeb48.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.jobmaster.JobMaster - Close ResourceManager connection 07309e1e02cf5b17c15dfda65b1f72a4: JobManager is shutting down..
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.jobmaster.slotpool.SlotPool - Suspending SlotPool.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.dispatcher.StandaloneDispatcher - Stopping all currently running jobs of dispatcher akka://flink/user/dispatcher1312ef65-a535-41c4-9b0c-d207b89eeb48.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.taskexecutor.slot.TaskSlotTable - Free slot TaskSlot(index:0, state:ALLOCATED, resource profile: ResourceProfile{cpuCores=1.0, heapMemoryInMB=42, directMemoryInMB=0, nativeMemoryInMB=0, networkMemoryInMB=0}, allocationId: AllocationID{99f39cac3d82873121599735025661ab}, jobId: cf0ab9f2675a2b72a8b13d477ce46f79).
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.jobmaster.slotpool.SlotPool - Stopping SlotPool.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Remove job cf0ab9f2675a2b72a8b13d477ce46f79 from job leader monitoring.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.resourcemanager.StandaloneResourceManager - Disconnect job manager a441355103a08d1a7badb2552b9e4d32@akka://flink/user/jobmanager_45 for job cf0ab9f2675a2b72a8b13d477ce46f79 from the resource manager.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Close JobManager connection for job cf0ab9f2675a2b72a8b13d477ce46f79.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Stopping TaskExecutor akka://flink/user/taskmanager_44.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.state.TaskExecutorLocalStateStoresManager - Shutting down TaskExecutorLocalStateStoresManager.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.resourcemanager.slotmanager.SlotManager - Closing the SlotManager.
[flink-akka.actor.default-dispatcher-4] INFO org.apache.flink.runtime.resourcemanager.slotmanager.SlotManager - Suspending the SlotManager.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.io.disk.iomanager.IOManager - I/O manager removed spill file directory /tmp/flink-io-79cfcbe0-71ce-4479-8b58-30a6cdac9391
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.io.network.NetworkEnvironment - Shutting down the network environment and its components.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.taskexecutor.JobLeaderService - Stop job leader service.
[flink-akka.actor.default-dispatcher-2] INFO org.apache.flink.runtime.taskexecutor.TaskExecutor - Stopped TaskExecutor akka://flink/user/taskmanager_44.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.rest.handler.legacy.backpressure.StackTraceSampleCoordinator - Shutting down stack trace sample coordinator.
[ForkJoinPool.commonPool-worker-2] INFO org.apache.flink.runtime.dispatcher.DispatcherRestEndpoint - Removing cache directory /tmp/flink-web-ui
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.dispatcher.StandaloneDispatcher - Stopped dispatcher akka://flink/user/dispatcher1312ef65-a535-41c4-9b0c-d207b89eeb48.
[flink-akka.actor.default-dispatcher-5] INFO org.apache.beam.runners.flink.metrics.FileReporter - wrote metrics to /tmp/flinktest-confFSlzDM/test-metrics.txt
[flink-akka.actor.default-dispatcher-5] INFO org.apache.flink.runtime.rpc.akka.AkkaRpcService - Stopping Akka RPC service.
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.blob.PermanentBlobCache - Shutting down BLOB cache
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.blob.TransientBlobCache - Shutting down BLOB cache
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.blob.BlobServer - Stopped BLOB server at 0.0.0.0:42191
[flink-akka.actor.default-dispatcher-3] INFO org.apache.flink.runtime.rpc.akka.AkkaRpcService - Stopped Akka RPC service.
[flink-runner-job-server] INFO org.apache.beam.runners.flink.FlinkRunner - Execution finished in 2161 msecs
[flink-runner-job-server] INFO org.apache.beam.runners.flink.FlinkRunner - Final accumulator values:
[flink-runner-job-server] INFO org.apache.beam.runners.flink.FlinkRunner - __metricscontainers : org.apache.beam.runners.core.metrics.MetricsContainerStepMap@b5a257e1
INFO:root:Job state changed to DONE
.INFO:root:removing conf dir: /tmp/flinktest-confFSlzDM

----------------------------------------------------------------------
Ran 27 tests in 120.136s

OK (skipped=6)
:beam-sdks-python:flinkCompatibilityMatrixStreamingDOCKER (Thread[Task worker for ':' Thread 7,5,main]) completed. Took 2 mins 2.199 secs.

FAILURE: Build failed with an exception.

* Where:
Build file '<https://builds.apache.org/job/beam_PostCommit_Python_VR_Flink/ws/src/sdks/python/build.gradle'> line: 347

* What went wrong:
Execution failed for task ':beam-sdks-python:flinkCompatibilityMatrixBatchDOCKER'.
> Process 'command 'sh'' finished with non-zero exit value 1

* Try:
Run with --stacktrace option to get the stack trace. Run with --debug option to get more log output. Run with --scan to get full insights.

* Get more help at https://help.gradle.org

Deprecated Gradle features were used in this build, making it incompatible with Gradle 5.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/4.10.2/userguide/command_line_interface.html#sec:command_line_warnings

BUILD FAILED in 14m 38s
61 actionable tasks: 56 executed, 4 from cache, 1 up-to-date

Publishing build scan...
https://gradle.com/s/zz37ql4tog66q

Build step 'Invoke Gradle script' changed build result to FAILURE
Build step 'Invoke Gradle script' marked build as failure

---------------------------------------------------------------------
To unsubscribe, e-mail: builds-unsubscribe@beam.apache.org
For additional commands, e-mail: builds-help@beam.apache.org