Build failed in Jenkins: beam_PostCommit_Python2 #1060

Apache Jenkins Server Fri, 22 Nov 2019 17:39:20 -0800

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


Changes:

[tweise] Documenting how to use Beam twitter handle (#10082)


------------------------------------------
[...truncated 1.05 MB...]
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN MapPartition 
(MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (1/2) 
(46468bbc79a1168e77921d5a28adb6cc) switched from DEPLOYING to RUNNING.
[CHAIN MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (1/2)] WARN 
org.apache.flink.metrics.MetricGroup - The operator name MapPartition 
(MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) exceeded the 80 characters length limit and was truncated.
[GroupReduce (GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(2/2)] INFO org.apache.flink.runtime.taskmanager.Task - GroupReduce 
(GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(2/2) (2eb964232f5587957c9770d0fddc10d4) switched from RUNNING to FINISHED.
[GroupReduce (GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(2/2)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources 
for GroupReduce (GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(2/2) (2eb964232f5587957c9770d0fddc10d4).
[GroupReduce (GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(1/2)] INFO org.apache.flink.runtime.taskmanager.Task - GroupReduce 
(GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(1/2) (2d93696b6c065a221451e0db78f70e57) switched from RUNNING to FINISHED.
[GroupReduce (GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(1/2)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources 
for GroupReduce (GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(1/2) (2d93696b6c065a221451e0db78f70e57).
[GroupReduce (GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(2/2)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem 
streams are closed for task GroupReduce (GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(2/2) (2eb964232f5587957c9770d0fddc10d4) [FINISHED]
[GroupReduce (GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(1/2)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem 
streams are closed for task GroupReduce (GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(1/2) (2d93696b6c065a221451e0db78f70e57) [FINISHED]
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task GroupReduce 
(GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
2eb964232f5587957c9770d0fddc10d4.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task GroupReduce 
(GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
2d93696b6c065a221451e0db78f70e57.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupReduce 
(GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(2/2) (2eb964232f5587957c9770d0fddc10d4) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupReduce 
(GroupReduce at 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group) 
(1/2) (2d93696b6c065a221451e0db78f70e57) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN MapPartition 
(MapPartition at [6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(2/2) (8a4bb4b32040ecc4350f1c0cf265b7bf) switched from CREATED to SCHEDULED.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN MapPartition 
(MapPartition at [6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(2/2) (8a4bb4b32040ecc4350f1c0cf265b7bf) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying CHAIN 
MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2) (attempt #0) to 96e512cf-0705-4365-a984-ecb453369d2e @ 
localhost (dataPort=-1)
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task CHAIN 
MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2).
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2)] INFO org.apache.flink.runtime.taskmanager.Task - CHAIN 
MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2) (8a4bb4b32040ecc4350f1c0cf265b7bf) switched from 
CREATED to DEPLOYING.
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2)] INFO org.apache.flink.runtime.taskmanager.Task - 
Creating FileSystem stream leak safety net for task CHAIN MapPartition 
(MapPartition at [6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(2/2) (8a4bb4b32040ecc4350f1c0cf265b7bf) [DEPLOYING]
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2)] INFO org.apache.flink.runtime.taskmanager.Task - 
Loading JAR files for task CHAIN MapPartition (MapPartition at [6]{Map(<lambda 
at external_test.py:389>), Map(<lambda at external_test.py:390>), assert_that}) 
-> FlatMap (FlatMap at ExtractOutput[0]) (2/2) 
(8a4bb4b32040ecc4350f1c0cf265b7bf) [DEPLOYING].
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2)] INFO org.apache.flink.runtime.taskmanager.Task - 
Registering task at network: CHAIN MapPartition (MapPartition at 
[6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(2/2) (8a4bb4b32040ecc4350f1c0cf265b7bf) [DEPLOYING].
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2)] INFO org.apache.flink.runtime.taskmanager.Task - CHAIN 
MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2) (8a4bb4b32040ecc4350f1c0cf265b7bf) switched from 
DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN MapPartition 
(MapPartition at [6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(2/2) (8a4bb4b32040ecc4350f1c0cf265b7bf) switched from DEPLOYING to RUNNING.
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2)] WARN org.apache.flink.metrics.MetricGroup - The 
operator name MapPartition (MapPartition at [6]{Map(<lambda at 
external_test.py:389>), Map(<lambda at external_test.py:390>), assert_that}) 
exceeded the 80 characters length limit and was truncated.
[CHAIN MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - CHAIN MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (2/2) 
(56cd699ab867a1cc8a361a288a30e57b) switched from RUNNING to FINISHED.
[CHAIN MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Freeing task resources for CHAIN 
MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (2/2) 
(56cd699ab867a1cc8a361a288a30e57b).
[CHAIN MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are 
closed for task CHAIN MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (2/2) 
(56cd699ab867a1cc8a361a288a30e57b) [FINISHED]
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task CHAIN 
MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) 
56cd699ab867a1cc8a361a288a30e57b.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN MapPartition 
(MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (2/2) 
(56cd699ab867a1cc8a361a288a30e57b) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN MapPartition 
(MapPartition at [6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(1/2) (b52714d41e71d1b28d70c1c5f0928260) switched from CREATED to SCHEDULED.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN MapPartition 
(MapPartition at [6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(1/2) (b52714d41e71d1b28d70c1c5f0928260) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying CHAIN 
MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2) (attempt #0) to 96e512cf-0705-4365-a984-ecb453369d2e @ 
localhost (dataPort=-1)
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task CHAIN 
MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2).
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2)] INFO org.apache.flink.runtime.taskmanager.Task - CHAIN 
MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2) (b52714d41e71d1b28d70c1c5f0928260) switched from 
CREATED to DEPLOYING.
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2)] INFO org.apache.flink.runtime.taskmanager.Task - 
Creating FileSystem stream leak safety net for task CHAIN MapPartition 
(MapPartition at [6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(1/2) (b52714d41e71d1b28d70c1c5f0928260) [DEPLOYING]
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2)] INFO org.apache.flink.runtime.taskmanager.Task - 
Loading JAR files for task CHAIN MapPartition (MapPartition at [6]{Map(<lambda 
at external_test.py:389>), Map(<lambda at external_test.py:390>), assert_that}) 
-> FlatMap (FlatMap at ExtractOutput[0]) (1/2) 
(b52714d41e71d1b28d70c1c5f0928260) [DEPLOYING].
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2)] INFO org.apache.flink.runtime.taskmanager.Task - 
Registering task at network: CHAIN MapPartition (MapPartition at 
[6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(1/2) (b52714d41e71d1b28d70c1c5f0928260) [DEPLOYING].
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2)] INFO org.apache.flink.runtime.taskmanager.Task - CHAIN 
MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2) (b52714d41e71d1b28d70c1c5f0928260) switched from 
DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN MapPartition 
(MapPartition at [6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(1/2) (b52714d41e71d1b28d70c1c5f0928260) switched from DEPLOYING to RUNNING.
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2)] WARN org.apache.flink.metrics.MetricGroup - The 
operator name MapPartition (MapPartition at [6]{Map(<lambda at 
external_test.py:389>), Map(<lambda at external_test.py:390>), assert_that}) 
exceeded the 80 characters length limit and was truncated.
[CHAIN MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - CHAIN MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (1/2) 
(46468bbc79a1168e77921d5a28adb6cc) switched from RUNNING to FINISHED.
[CHAIN MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Freeing task resources for CHAIN 
MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (1/2) 
(46468bbc79a1168e77921d5a28adb6cc).
[CHAIN MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are 
closed for task CHAIN MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (1/2) 
(46468bbc79a1168e77921d5a28adb6cc) [FINISHED]
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task CHAIN 
MapPartition (MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) 
46468bbc79a1168e77921d5a28adb6cc.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN MapPartition 
(MapPartition at 
[2]ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/{Merge, 
ExtractOutputs}) -> FlatMap (FlatMap at ExtractOutput[0]) (1/2) 
(46468bbc79a1168e77921d5a28adb6cc) switched from RUNNING to FINISHED.
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2)] INFO org.apache.flink.runtime.taskmanager.Task - CHAIN 
MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2) (8a4bb4b32040ecc4350f1c0cf265b7bf) switched from 
RUNNING to FINISHED.
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2)] INFO org.apache.flink.runtime.taskmanager.Task - 
Freeing task resources for CHAIN MapPartition (MapPartition at [6]{Map(<lambda 
at external_test.py:389>), Map(<lambda at external_test.py:390>), assert_that}) 
-> FlatMap (FlatMap at ExtractOutput[0]) (2/2) 
(8a4bb4b32040ecc4350f1c0cf265b7bf).
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (2/2)] INFO org.apache.flink.runtime.taskmanager.Task - 
Ensuring all FileSystem streams are closed for task CHAIN MapPartition 
(MapPartition at [6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(2/2) (8a4bb4b32040ecc4350f1c0cf265b7bf) [FINISHED]
[flink-akka.actor.default-dispatcher-8] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task CHAIN 
MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) 8a4bb4b32040ecc4350f1c0cf265b7bf.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) (1/2) 
(f647d232d6015cf3392ba25f510e1d77) switched from CREATED to SCHEDULED.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) (1/2) 
(f647d232d6015cf3392ba25f510e1d77) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) (1/2) (attempt #0) to 
96e512cf-0705-4365-a984-ecb453369d2e @ localhost (dataPort=-1)
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) (2/2) 
(a731ed755c043af5df50af039d65df0f) switched from CREATED to SCHEDULED.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) (2/2) 
(a731ed755c043af5df50af039d65df0f) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) (2/2) (attempt #0) to 
96e512cf-0705-4365-a984-ecb453369d2e @ localhost (dataPort=-1)
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN MapPartition 
(MapPartition at [6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(2/2) (8a4bb4b32040ecc4350f1c0cf265b7bf) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-8] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) (1/2).
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - GroupReduce (GroupReduce at 
assert_that/Group/GroupByKey) (1/2) (f647d232d6015cf3392ba25f510e1d77) switched 
from CREATED to DEPLOYING.
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak 
safety net for task GroupReduce (GroupReduce at assert_that/Group/GroupByKey) 
(1/2) (f647d232d6015cf3392ba25f510e1d77) [DEPLOYING]
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task 
GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (1/2) 
(f647d232d6015cf3392ba25f510e1d77) [DEPLOYING].
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Registering task at network: 
GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (1/2) 
(f647d232d6015cf3392ba25f510e1d77) [DEPLOYING].
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - GroupReduce (GroupReduce at 
assert_that/Group/GroupByKey) (1/2) (f647d232d6015cf3392ba25f510e1d77) switched 
from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) (1/2) 
(f647d232d6015cf3392ba25f510e1d77) switched from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-8] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) (2/2).
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - GroupReduce (GroupReduce at 
assert_that/Group/GroupByKey) (2/2) (a731ed755c043af5df50af039d65df0f) switched 
from CREATED to DEPLOYING.
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak 
safety net for task GroupReduce (GroupReduce at assert_that/Group/GroupByKey) 
(2/2) (a731ed755c043af5df50af039d65df0f) [DEPLOYING]
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task 
GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (2/2) 
(a731ed755c043af5df50af039d65df0f) [DEPLOYING].
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Registering task at network: 
GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (2/2) 
(a731ed755c043af5df50af039d65df0f) [DEPLOYING].
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - GroupReduce (GroupReduce at 
assert_that/Group/GroupByKey) (2/2) (a731ed755c043af5df50af039d65df0f) switched 
from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-8] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) (2/2) 
(a731ed755c043af5df50af039d65df0f) switched from DEPLOYING to RUNNING.
[CHAIN Filter (UnionFixFilter) -> Map (Key Extractor) -> GroupCombine 
(GroupCombine at GroupCombine: assert_that/Group/GroupByKey) -> Map (Key 
Extractor) (2/2)] INFO org.apache.flink.runtime.taskmanager.Task - CHAIN Filter 
(UnionFixFilter) -> Map (Key Extractor) -> GroupCombine (GroupCombine at 
GroupCombine: assert_that/Group/GroupByKey) -> Map (Key Extractor) (2/2) 
(43bad79a8c808637ac7e7b6de1197200) switched from RUNNING to FINISHED.
[CHAIN Filter (UnionFixFilter) -> Map (Key Extractor) -> GroupCombine 
(GroupCombine at GroupCombine: assert_that/Group/GroupByKey) -> Map (Key 
Extractor) (2/2)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task 
resources for CHAIN Filter (UnionFixFilter) -> Map (Key Extractor) -> 
GroupCombine (GroupCombine at GroupCombine: assert_that/Group/GroupByKey) -> 
Map (Key Extractor) (2/2) (43bad79a8c808637ac7e7b6de1197200).
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2)] INFO org.apache.flink.runtime.taskmanager.Task - CHAIN 
MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2) (b52714d41e71d1b28d70c1c5f0928260) switched from 
RUNNING to FINISHED.
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2)] INFO org.apache.flink.runtime.taskmanager.Task - 
Freeing task resources for CHAIN MapPartition (MapPartition at [6]{Map(<lambda 
at external_test.py:389>), Map(<lambda at external_test.py:390>), assert_that}) 
-> FlatMap (FlatMap at ExtractOutput[0]) (1/2) 
(b52714d41e71d1b28d70c1c5f0928260).
[CHAIN MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) (1/2)] INFO org.apache.flink.runtime.taskmanager.Task - 
Ensuring all FileSystem streams are closed for task CHAIN MapPartition 
(MapPartition at [6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(1/2) (b52714d41e71d1b28d70c1c5f0928260) [FINISHED]
[CHAIN Filter (UnionFixFilter) -> Map (Key Extractor) -> GroupCombine 
(GroupCombine at GroupCombine: assert_that/Group/GroupByKey) -> Map (Key 
Extractor) (2/2)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all 
FileSystem streams are closed for task CHAIN Filter (UnionFixFilter) -> Map 
(Key Extractor) -> GroupCombine (GroupCombine at GroupCombine: 
assert_that/Group/GroupByKey) -> Map (Key Extractor) (2/2) 
(43bad79a8c808637ac7e7b6de1197200) [FINISHED]
[CHAIN Filter (UnionFixFilter) -> Map (Key Extractor) -> GroupCombine 
(GroupCombine at GroupCombine: assert_that/Group/GroupByKey) -> Map (Key 
Extractor) (1/2)] INFO org.apache.flink.runtime.taskmanager.Task - CHAIN Filter 
(UnionFixFilter) -> Map (Key Extractor) -> GroupCombine (GroupCombine at 
GroupCombine: assert_that/Group/GroupByKey) -> Map (Key Extractor) (1/2) 
(449440aa417760e31d3799dc1223f74d) switched from RUNNING to FINISHED.
[CHAIN Filter (UnionFixFilter) -> Map (Key Extractor) -> GroupCombine 
(GroupCombine at GroupCombine: assert_that/Group/GroupByKey) -> Map (Key 
Extractor) (1/2)] INFO org.apache.flink.runtime.taskmanager.Task - Freeing task 
resources for CHAIN Filter (UnionFixFilter) -> Map (Key Extractor) -> 
GroupCombine (GroupCombine at GroupCombine: assert_that/Group/GroupByKey) -> 
Map (Key Extractor) (1/2) (449440aa417760e31d3799dc1223f74d).
[CHAIN Filter (UnionFixFilter) -> Map (Key Extractor) -> GroupCombine 
(GroupCombine at GroupCombine: assert_that/Group/GroupByKey) -> Map (Key 
Extractor) (1/2)] INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all 
FileSystem streams are closed for task CHAIN Filter (UnionFixFilter) -> Map 
(Key Extractor) -> GroupCombine (GroupCombine at GroupCombine: 
assert_that/Group/GroupByKey) -> Map (Key Extractor) (1/2) 
(449440aa417760e31d3799dc1223f74d) [FINISHED]
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task CHAIN 
MapPartition (MapPartition at [6]{Map(<lambda at external_test.py:389>), 
Map(<lambda at external_test.py:390>), assert_that}) -> FlatMap (FlatMap at 
ExtractOutput[0]) b52714d41e71d1b28d70c1c5f0928260.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task CHAIN Filter 
(UnionFixFilter) -> Map (Key Extractor) -> GroupCombine (GroupCombine at 
GroupCombine: assert_that/Group/GroupByKey) -> Map (Key Extractor) 
43bad79a8c808637ac7e7b6de1197200.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task CHAIN Filter 
(UnionFixFilter) -> Map (Key Extractor) -> GroupCombine (GroupCombine at 
GroupCombine: assert_that/Group/GroupByKey) -> Map (Key Extractor) 
449440aa417760e31d3799dc1223f74d.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN MapPartition 
(MapPartition at [6]{Map(<lambda at external_test.py:389>), Map(<lambda at 
external_test.py:390>), assert_that}) -> FlatMap (FlatMap at ExtractOutput[0]) 
(1/2) (b52714d41e71d1b28d70c1c5f0928260) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN Filter 
(UnionFixFilter) -> Map (Key Extractor) -> GroupCombine (GroupCombine at 
GroupCombine: assert_that/Group/GroupByKey) -> Map (Key Extractor) (2/2) 
(43bad79a8c808637ac7e7b6de1197200) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - CHAIN Filter 
(UnionFixFilter) -> Map (Key Extractor) -> GroupCombine (GroupCombine at 
GroupCombine: assert_that/Group/GroupByKey) -> Map (Key Extractor) (1/2) 
(449440aa417760e31d3799dc1223f74d) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2) 
(6f4a6502c4700cf60590875b57d073df) switched from CREATED to SCHEDULED.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2) 
(6f4a6502c4700cf60590875b57d073df) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2) (attempt #0) to 
96e512cf-0705-4365-a984-ecb453369d2e @ localhost (dataPort=-1)
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2).
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - MapPartition (MapPartition at 
[3]assert_that/{Group, Unkey, Match}) (2/2) (6f4a6502c4700cf60590875b57d073df) 
switched from CREATED to DEPLOYING.
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream 
leak safety net for task MapPartition (MapPartition at [3]assert_that/{Group, 
Unkey, Match}) (2/2) (6f4a6502c4700cf60590875b57d073df) [DEPLOYING]
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task 
MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2) 
(6f4a6502c4700cf60590875b57d073df) [DEPLOYING].
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: 
MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2) 
(6f4a6502c4700cf60590875b57d073df) [DEPLOYING].
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - MapPartition (MapPartition at 
[3]assert_that/{Group, Unkey, Match}) (2/2) (6f4a6502c4700cf60590875b57d073df) 
switched from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-8] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2) 
(6f4a6502c4700cf60590875b57d073df) switched from DEPLOYING to RUNNING.
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - GroupReduce (GroupReduce at 
assert_that/Group/GroupByKey) (2/2) (a731ed755c043af5df50af039d65df0f) switched 
from RUNNING to FINISHED.
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Freeing task resources for 
GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (2/2) 
(a731ed755c043af5df50af039d65df0f).
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are 
closed for task GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (2/2) 
(a731ed755c043af5df50af039d65df0f) [FINISHED]
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - GroupReduce (GroupReduce at 
assert_that/Group/GroupByKey) (1/2) (f647d232d6015cf3392ba25f510e1d77) switched 
from RUNNING to FINISHED.
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Freeing task resources for 
GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (1/2) 
(f647d232d6015cf3392ba25f510e1d77).
[flink-akka.actor.default-dispatcher-8] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) a731ed755c043af5df50af039d65df0f.
[GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are 
closed for task GroupReduce (GroupReduce at assert_that/Group/GroupByKey) (1/2) 
(f647d232d6015cf3392ba25f510e1d77) [FINISHED]
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2) 
(326d4816e82dc7be4c3d5c8438808066) switched from CREATED to SCHEDULED.
[flink-akka.actor.default-dispatcher-8] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) f647d232d6015cf3392ba25f510e1d77.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2) 
(326d4816e82dc7be4c3d5c8438808066) switched from SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2) (attempt #0) to 
96e512cf-0705-4365-a984-ecb453369d2e @ localhost (dataPort=-1)
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) (2/2) 
(a731ed755c043af5df50af039d65df0f) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - GroupReduce 
(GroupReduce at assert_that/Group/GroupByKey) (1/2) 
(f647d232d6015cf3392ba25f510e1d77) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2).
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - MapPartition (MapPartition at 
[3]assert_that/{Group, Unkey, Match}) (1/2) (326d4816e82dc7be4c3d5c8438808066) 
switched from CREATED to DEPLOYING.
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream 
leak safety net for task MapPartition (MapPartition at [3]assert_that/{Group, 
Unkey, Match}) (1/2) (326d4816e82dc7be4c3d5c8438808066) [DEPLOYING]
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task 
MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2) 
(326d4816e82dc7be4c3d5c8438808066) [DEPLOYING].
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - Registering task at network: 
MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2) 
(326d4816e82dc7be4c3d5c8438808066) [DEPLOYING].
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - MapPartition (MapPartition at 
[3]assert_that/{Group, Unkey, Match}) (1/2) (326d4816e82dc7be4c3d5c8438808066) 
switched from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2) 
(326d4816e82dc7be4c3d5c8438808066) switched from DEPLOYING to RUNNING.
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - MapPartition (MapPartition at 
[3]assert_that/{Group, Unkey, Match}) (2/2) (6f4a6502c4700cf60590875b57d073df) 
switched from RUNNING to FINISHED.
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for 
MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2) 
(6f4a6502c4700cf60590875b57d073df).
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - DataSink 
(DiscardingOutput) (2/2) (c5c2fdda5fdf16e695f383d80d55a921) switched from 
CREATED to SCHEDULED.
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem 
streams are closed for task MapPartition (MapPartition at 
[3]assert_that/{Group, Unkey, Match}) (2/2) (6f4a6502c4700cf60590875b57d073df) 
[FINISHED]
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) 
6f4a6502c4700cf60590875b57d073df.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - DataSink 
(DiscardingOutput) (2/2) (c5c2fdda5fdf16e695f383d80d55a921) switched from 
SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying DataSink 
(DiscardingOutput) (2/2) (attempt #0) to 96e512cf-0705-4365-a984-ecb453369d2e @ 
localhost (dataPort=-1)
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) (2/2) 
(6f4a6502c4700cf60590875b57d073df) switched from RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task DataSink 
(DiscardingOutput) (2/2).
[DataSink (DiscardingOutput) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - DataSink (DiscardingOutput) (2/2) 
(c5c2fdda5fdf16e695f383d80d55a921) switched from CREATED to DEPLOYING.
[DataSink (DiscardingOutput) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak 
safety net for task DataSink (DiscardingOutput) (2/2) 
(c5c2fdda5fdf16e695f383d80d55a921) [DEPLOYING]
[DataSink (DiscardingOutput) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task DataSink 
(DiscardingOutput) (2/2) (c5c2fdda5fdf16e695f383d80d55a921) [DEPLOYING].
[DataSink (DiscardingOutput) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Registering task at network: 
DataSink (DiscardingOutput) (2/2) (c5c2fdda5fdf16e695f383d80d55a921) 
[DEPLOYING].
[DataSink (DiscardingOutput) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - DataSink (DiscardingOutput) (2/2) 
(c5c2fdda5fdf16e695f383d80d55a921) switched from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - DataSink 
(DiscardingOutput) (2/2) (c5c2fdda5fdf16e695f383d80d55a921) switched from 
DEPLOYING to RUNNING.
[DataSink (DiscardingOutput) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - DataSink (DiscardingOutput) (2/2) 
(c5c2fdda5fdf16e695f383d80d55a921) switched from RUNNING to FINISHED.
[DataSink (DiscardingOutput) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Freeing task resources for DataSink 
(DiscardingOutput) (2/2) (c5c2fdda5fdf16e695f383d80d55a921).
[DataSink (DiscardingOutput) (2/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are 
closed for task DataSink (DiscardingOutput) (2/2) 
(c5c2fdda5fdf16e695f383d80d55a921) [FINISHED]
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task DataSink 
(DiscardingOutput) c5c2fdda5fdf16e695f383d80d55a921.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - DataSink 
(DiscardingOutput) (2/2) (c5c2fdda5fdf16e695f383d80d55a921) switched from 
RUNNING to FINISHED.
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - MapPartition (MapPartition at 
[3]assert_that/{Group, Unkey, Match}) (1/2) (326d4816e82dc7be4c3d5c8438808066) 
switched from RUNNING to FINISHED.
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - Freeing task resources for 
MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2) 
(326d4816e82dc7be4c3d5c8438808066).
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - DataSink 
(DiscardingOutput) (1/2) (66b1362f3e952d20aae0e02fd876c284) switched from 
CREATED to SCHEDULED.
[MapPartition (MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2)] 
INFO org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem 
streams are closed for task MapPartition (MapPartition at 
[3]assert_that/{Group, Unkey, Match}) (1/2) (326d4816e82dc7be4c3d5c8438808066) 
[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 MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) 
326d4816e82dc7be4c3d5c8438808066.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - DataSink 
(DiscardingOutput) (1/2) (66b1362f3e952d20aae0e02fd876c284) switched from 
SCHEDULED to DEPLOYING.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - Deploying DataSink 
(DiscardingOutput) (1/2) (attempt #0) to 96e512cf-0705-4365-a984-ecb453369d2e @ 
localhost (dataPort=-1)
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Received task DataSink 
(DiscardingOutput) (1/2).
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - MapPartition 
(MapPartition at [3]assert_that/{Group, Unkey, Match}) (1/2) 
(326d4816e82dc7be4c3d5c8438808066) switched from RUNNING to FINISHED.
[DataSink (DiscardingOutput) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - DataSink (DiscardingOutput) (1/2) 
(66b1362f3e952d20aae0e02fd876c284) switched from CREATED to DEPLOYING.
[DataSink (DiscardingOutput) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Creating FileSystem stream leak 
safety net for task DataSink (DiscardingOutput) (1/2) 
(66b1362f3e952d20aae0e02fd876c284) [DEPLOYING]
[DataSink (DiscardingOutput) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Loading JAR files for task DataSink 
(DiscardingOutput) (1/2) (66b1362f3e952d20aae0e02fd876c284) [DEPLOYING].
[DataSink (DiscardingOutput) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Registering task at network: 
DataSink (DiscardingOutput) (1/2) (66b1362f3e952d20aae0e02fd876c284) 
[DEPLOYING].
[DataSink (DiscardingOutput) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - DataSink (DiscardingOutput) (1/2) 
(66b1362f3e952d20aae0e02fd876c284) switched from DEPLOYING to RUNNING.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - DataSink 
(DiscardingOutput) (1/2) (66b1362f3e952d20aae0e02fd876c284) switched from 
DEPLOYING to RUNNING.
[DataSink (DiscardingOutput) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - DataSink (DiscardingOutput) (1/2) 
(66b1362f3e952d20aae0e02fd876c284) switched from RUNNING to FINISHED.
[DataSink (DiscardingOutput) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Freeing task resources for DataSink 
(DiscardingOutput) (1/2) (66b1362f3e952d20aae0e02fd876c284).
[DataSink (DiscardingOutput) (1/2)] INFO 
org.apache.flink.runtime.taskmanager.Task - Ensuring all FileSystem streams are 
closed for task DataSink (DiscardingOutput) (1/2) 
(66b1362f3e952d20aae0e02fd876c284) [FINISHED]
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Un-registering task and 
sending final execution state FINISHED to JobManager for task DataSink 
(DiscardingOutput) 66b1362f3e952d20aae0e02fd876c284.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - DataSink 
(DiscardingOutput) (1/2) (66b1362f3e952d20aae0e02fd876c284) switched from 
RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.executiongraph.ExecutionGraph - Job 
BeamApp-root-1123013827-e2406908 (16aa9c49694c649b2832d7c81b167c7c) switched 
from state RUNNING to FINISHED.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.dispatcher.StandaloneDispatcher - Job 
16aa9c49694c649b2832d7c81b167c7c reached globally terminal state FINISHED.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.jobmaster.JobMaster - Stopping the JobMaster for job 
BeamApp-root-1123013827-e2406908(16aa9c49694c649b2832d7c81b167c7c).
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.jobmaster.slotpool.SlotPoolImpl - Suspending SlotPool.
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.jobmaster.JobMaster - Close ResourceManager connection 
af513b695bbf6f1a73b507404b771b9f: JobManager is shutting down..
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.slot.TaskSlotTable - Free slot 
TaskSlot(index:0, state:ACTIVE, resource profile: 
ResourceProfile{cpuCores=1.7976931348623157E308, heapMemoryInMB=2147483647, 
directMemoryInMB=2147483647, nativeMemoryInMB=2147483647, 
networkMemoryInMB=2147483647, managedMemoryInMB=8138}, allocationId: 
679cc61472d40dfc534b4e39b91c17b5, jobId: 16aa9c49694c649b2832d7c81b167c7c).
[flink-akka.actor.default-dispatcher-5] INFO 
org.apache.flink.runtime.jobmaster.slotpool.SlotPoolImpl - Stopping SlotPool.
[flink-akka.actor.default-dispatcher-8] INFO 
org.apache.flink.runtime.resourcemanager.StandaloneResourceManager - Disconnect 
job manager 86d5cff27ca22ac290ec8e29187442ab@akka://flink/user/jobmanager_1 for 
job 16aa9c49694c649b2832d7c81b167c7c from the resource manager.
[flink-runner-job-invoker] INFO 
org.apache.flink.runtime.minicluster.MiniCluster - Shutting down Flink Mini 
Cluster
[flink-runner-job-invoker] INFO 
org.apache.flink.runtime.dispatcher.DispatcherRestEndpoint - Shutting down rest 
endpoint.
[mini-cluster-io-thread-15] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - JobManager for job 
16aa9c49694c649b2832d7c81b167c7c with leader id 
86d5cff27ca22ac290ec8e29187442ab lost leadership.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.slot.TaskSlotTable - Free slot 
TaskSlot(index:1, state:ACTIVE, resource profile: 
ResourceProfile{cpuCores=1.7976931348623157E308, heapMemoryInMB=2147483647, 
directMemoryInMB=2147483647, nativeMemoryInMB=2147483647, 
networkMemoryInMB=2147483647, managedMemoryInMB=8138}, allocationId: 
6f1723f2c7cd7a7655e02a799895f397, jobId: 16aa9c49694c649b2832d7c81b167c7c).
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.JobLeaderService - Remove job 
16aa9c49694c649b2832d7c81b167c7c from job leader monitoring.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Close JobManager 
connection for job 16aa9c49694c649b2832d7c81b167c7c.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Close JobManager 
connection for job 16aa9c49694c649b2832d7c81b167c7c.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.JobLeaderService - Cannot reconnect to 
job 16aa9c49694c649b2832d7c81b167c7c because it is not registered.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Stopping TaskExecutor 
akka://flink/user/taskmanager_0.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Close ResourceManager 
connection af513b695bbf6f1a73b507404b771b9f.
[flink-akka.actor.default-dispatcher-8] INFO 
org.apache.flink.runtime.resourcemanager.StandaloneResourceManager - Closing 
TaskExecutor connection 96e512cf-0705-4365-a984-ecb453369d2e because: The 
TaskExecutor is shutting down.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.JobLeaderService - Stop job leader 
service.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.state.TaskExecutorLocalStateStoresManager - Shutting 
down TaskExecutorLocalStateStoresManager.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.io.disk.FileChannelManagerImpl - FileChannelManager 
removed spill file directory /tmp/flink-io-e1516639-b500-44ab-8abb-2a37b8d78ce1
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.io.network.NettyShuffleEnvironment - Shutting down the 
network environment and its components.
[ForkJoinPool.commonPool-worker-2] INFO 
org.apache.flink.runtime.dispatcher.DispatcherRestEndpoint - Removing cache 
directory /tmp/flink-web-ui
[ForkJoinPool.commonPool-worker-2] INFO 
org.apache.flink.runtime.dispatcher.DispatcherRestEndpoint - Shut down complete.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.resourcemanager.StandaloneResourceManager - Shut down 
cluster because application is in CANCELED, diagnostics 
DispatcherResourceManagerComponent has been closed..
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.io.disk.FileChannelManagerImpl - FileChannelManager 
removed spill file directory 
/tmp/flink-netty-shuffle-981151bd-b6d1-4c86-a5ee-37e5ba8ad5ec
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.KvStateService - Shutting down the 
kvState service and its components.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.JobLeaderService - Stop job leader 
service.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.filecache.FileCache - removed file cache directory 
/tmp/flink-dist-cache-b71e0bc1-a9d6-4288-b6f6-f3c8b020a07f
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.dispatcher.StandaloneDispatcher - Stopping dispatcher 
akka://flink/user/dispatcher.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.dispatcher.StandaloneDispatcher - Stopping all 
currently running jobs of dispatcher akka://flink/user/dispatcher.
[flink-akka.actor.default-dispatcher-8] INFO 
org.apache.flink.runtime.resourcemanager.slotmanager.SlotManagerImpl - Closing 
the SlotManager.
[flink-akka.actor.default-dispatcher-8] INFO 
org.apache.flink.runtime.resourcemanager.slotmanager.SlotManagerImpl - 
Suspending the SlotManager.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.taskexecutor.TaskExecutor - Stopped TaskExecutor 
akka://flink/user/taskmanager_0.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.rest.handler.legacy.backpressure.StackTraceSampleCoordinator
 - Shutting down stack trace sample coordinator.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.dispatcher.StandaloneDispatcher - Stopped dispatcher 
akka://flink/user/dispatcher.
[flink-akka.actor.default-dispatcher-7] INFO 
org.apache.flink.runtime.rpc.akka.AkkaRpcService - Stopping Akka RPC service.
[flink-metrics-2] INFO akka.remote.RemoteActorRefProvider$RemotingTerminator - 
Shutting down remote daemon.
[flink-metrics-2] INFO akka.remote.RemoteActorRefProvider$RemotingTerminator - 
Remote daemon shut down; proceeding with flushing remote transports.
[flink-metrics-2] INFO akka.remote.RemoteActorRefProvider$RemotingTerminator - 
Remoting shut down.
[flink-metrics-2] INFO org.apache.flink.runtime.rpc.akka.AkkaRpcService - 
Stopping Akka RPC service.
[flink-metrics-2] INFO org.apache.flink.runtime.rpc.akka.AkkaRpcService - 
Stopped Akka RPC service.
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.blob.PermanentBlobCache - Shutting down BLOB cache
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.blob.TransientBlobCache - Shutting down BLOB cache
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.blob.BlobServer - Stopped BLOB server at 0.0.0.0:39613
[flink-akka.actor.default-dispatcher-6] INFO 
org.apache.flink.runtime.rpc.akka.AkkaRpcService - Stopped Akka RPC service.
[flink-runner-job-invoker] INFO 
org.apache.beam.runners.flink.FlinkPipelineRunner - Execution finished in 16686 
msecs
[flink-runner-job-invoker] INFO 
org.apache.beam.runners.flink.FlinkPipelineRunner - Final accumulator values:
[flink-runner-job-invoker] INFO 
org.apache.beam.runners.flink.FlinkPipelineRunner - __metricscontainers : 
MetricQueryResults(Counters(ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group.out/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_14:0}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(<lambda at external_test.py:385>)_18}: 
0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(<lambda at external_test.py:385>)_18}: 
0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Create/FlatMap(<lambda at 
core.py:2532>)_26}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_24:0:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/WindowInto(WindowIntoFn)_29}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_9}: 12, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Match_41}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/pair_with_0_32}: 7, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(<lambda at external_test.py:385>)_18}: 
0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_9:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_21}: 3, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=external_1root/ParDo(Anonymous)/ParMultiDo(Anonymous)}: 0, 
37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=external_1root/ParDo(Anonymous)/ParMultiDo(Anonymous)}: 0, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 
{PTRANSFORM=ref_AppliedPTransform_assert_that/Group/Map(_merge_tagged_vals_under_key)_39}:
 0, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=external_1root/ParDo(Anonymous)/ParMultiDo(Anonymous)}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_23}: 3, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_20}: 3, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Match_41}: 0, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Create/FlatMap(<lambda at core.py:2532>)_4}: 
0, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_1:0}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Create/Map(decode)_16}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_17:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(<lambda at external_test.py:389>)_21}: 
0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(unicode)_17}: 0, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_1:0}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Create/Map(decode)_16}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(unicode)_17}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/pair_with_1_33}: 7, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Create/FlatMap(<lambda at core.py:2532>)_4}: 
0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_22}: 1, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_1:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/pair_with_1_33}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_24:1:0}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Create/FlatMap(<lambda at 
core.py:2532>)_26}: 0, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_2:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/pair_with_1_33}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_10}: 12, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_24:1}: 3, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_12:0}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_11}: 12, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_12}: 12, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Unkey_40}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Create/Map(decode)_16}: 0, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Unkey_40}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_15}: 3, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_16}: 3, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:element_count:v1 
{PCOLLECTION=ref_PCollection_PCollection_12}: 12, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Create/FlatMap(<lambda at 
core.py:2532>)_26}: 0, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_2:0}: 0, 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group.out/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 
{PTRANSFORM=ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/ExtractOutputs}:
 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_14}: 3, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Create/FlatMap(<lambda at core.py:2532>)_4}: 
13, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_12:0}: 0, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 
{PTRANSFORM=ref_AppliedPTransform_assert_that/Group/Map(_merge_tagged_vals_under_key)_39}:
 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_24:0}: 1, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_12:0}: 0, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/write/pcollection:0}: 0, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_12:0}: 0, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_2:0}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_18}: 1, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/write/pcollection:0}: 0, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:element_count:v1 
{PCOLLECTION=ref_PCollection_PCollection_13}: 6, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Create/Map(decode)_28}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_17}: 1, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 
{PTRANSFORM=ref_AppliedPTransform_assert_that/Group/Map(_merge_tagged_vals_under_key)_39}:
 0, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=fn/write/pcollection:0}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_19}: 1, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_17:0}: 0, 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group.out/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_14}: 3, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Create/Map(decode)_28}: 0, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_27:0}: 0, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Unkey_40}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_24:1:0}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Create/Map(decode)_16}: 0, 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group.out/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 
{PTRANSFORM=ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Merge}:
 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Create/Map(decode)_28}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Create/Map(decode)_28}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_17:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/pair_with_1_33}: 7, 
37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:element_count:v1 
{PCOLLECTION=external_2root/Init/Map/ParMultiDo(Anonymous).output}: 6, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_27}: 1, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_29}: 1, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_28}: 1, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_1:0}: 0, 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group.out/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/read/pcollection_1:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/ToVoidKey_30}: 0, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Unkey_40}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_17:0}: 0, 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group.out/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/read/pcollection_1:0}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_12:0}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_9:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/WindowInto(WindowIntoFn)_29}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(<lambda at external_test.py:385>)_18}: 
0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_9:0}: 0, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 
{PTRANSFORM=ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Precombine}:
 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_9:0}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_12:0}: 0, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:element_count:v1 
{PCOLLECTION=ref_PCollection_PCollection_2}: 12, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_27:0}: 0, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=external_2root/Init/Map/ParMultiDo(Anonymous)}: 0, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:element_count:v1 
{PCOLLECTION=ref_PCollection_PCollection_1}: 1, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 
{PTRANSFORM=ref_AppliedPTransform_assert_that/Group/Map(_merge_tagged_vals_under_key)_39}:
 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/pair_with_0_32}: 7, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_2:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/WindowInto(WindowIntoFn)_29}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_14:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/Flatten_34}: 0, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Match_41}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/Flatten_34}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/WindowInto(WindowIntoFn)_29}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(<lambda at external_test.py:390>)_22}: 
0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/ToVoidKey_30}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(<lambda at external_test.py:389>)_21}: 
0, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 
{PTRANSFORM=ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Precombine}:
 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/Flatten_34}: 0, 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group.out/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_14:0}: 0, 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group.out/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_14:0}: 0, 37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=external_2root/Init/Map/ParMultiDo(Anonymous)}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_14:0}: 0, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=ref_PCollection_PCollection_30}: 1, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/Flatten_34}: 0, 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group.out/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=pcollection_1}: 3, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Match_41}: 0, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Create/FlatMap(<lambda at core.py:2532>)_4}: 
13, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_24:0:0}: 0, 
ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Group.out/beam:env:docker:v1:0:beam:metric:element_count:v1
 {PCOLLECTION=pcollection_2}: 3, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(<lambda at external_test.py:389>)_21}: 
0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/Flatten_34}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_14:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_24:1:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_14:0}: 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(unicode)_17}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_24:1:0}: 0, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_27:0}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/Flatten_34}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(<lambda at external_test.py:390>)_22}: 
0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(<lambda at external_test.py:389>)_21}: 
0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/ToVoidKey_30}: 0, 37Map(<lambda 
at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=external_2root/Init/Map/ParMultiDo(Anonymous)}: 0, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=fn/read/ref_PCollection_PCollection_27:0}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/Flatten_34}: 0, 
37Map(<lambda at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:element_count:v1 
{PCOLLECTION=pcollection}: 5, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/ToVoidKey_30}: 0, 37Map(<lambda 
at 
external_test.py:385>).None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 
{PTRANSFORM=ExternalTransform(beam:transforms:xlang:count)/Combine.perKey(Count)/Precombine}:
 0, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:ptransform_execution_time:total_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(unicode)_17}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_24:0:0}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Create/FlatMap(<lambda at 
core.py:2532>)_26}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/pair_with_0_32}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(<lambda at external_test.py:390>)_22}: 
0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:finish_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/pair_with_0_32}: 0, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:pardo_execution_time:process_bundle_msecs:v1
 {PTRANSFORM=fn/write/ref_PCollection_PCollection_24:0:0}: 0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_Map(<lambda at external_test.py:390>)_22}: 
0, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:pardo_execution_time:start_bundle_msecs:v1
 {PTRANSFORM=ref_AppliedPTransform_assert_that/Group/Flatten_34}: 
0)Distributions(46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_21}: DistributionResult{sum=57, 
count=3, min=19, max=19}, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_20}: DistributionResult{sum=54, 
count=3, min=18, max=18}, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_23}: DistributionResult{sum=63, 
count=3, min=21, max=21}, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_22}: DistributionResult{sum=17, 
count=1, min=17, max=17}, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_30}: DistributionResult{sum=14, 
count=1, min=14, max=14}, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_19}: DistributionResult{sum=15, 
count=1, min=15, max=15}, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_17}: DistributionResult{sum=13, 
count=1, min=13, max=13}, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_28}: DistributionResult{sum=41, 
count=1, min=41, max=41}, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_18}: DistributionResult{sum=16, 
count=1, min=16, max=16}, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1 
{PCOLLECTION=ref_PCollection_PCollection_2}: DistributionResult{sum=180, 
count=12, min=15, max=15}, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_29}: DistributionResult{sum=33, 
count=1, min=33, max=33}, 
14Create/Impulse.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1 
{PCOLLECTION=ref_PCollection_PCollection_1}: DistributionResult{sum=13, 
count=1, min=13, max=13}, 
42assert_that/Group/GroupByKey/GroupByWindow.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_27}: DistributionResult{sum=58, 
count=1, min=58, max=58}, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_24:1}: DistributionResult{sum=72, 
count=3, min=24, max=24}, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_10}: DistributionResult{sum=168, 
count=12, min=14, max=14}, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_12}: DistributionResult{sum=168, 
count=12, min=14, max=14}, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_11}: DistributionResult{sum=168, 
count=12, min=14, max=14}, 
26assert_that/Create/Impulse.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_24:0}: DistributionResult{sum=19, 
count=1, min=19, max=19}, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_14}: DistributionResult{sum=45, 
count=3, min=15, max=15}, 
48Create/MaybeReshuffle/Reshuffle/RemoveRandomKeys.None/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_9}: DistributionResult{sum=192, 
count=12, min=16, max=16}, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_16}: DistributionResult{sum=54, 
count=3, min=18, max=18}, 
46ExternalTransform(beam:transforms:xlang:count).org.apache.beam.sdk.values.PCollection.<init>:400#554dcd2b40b5f04b/beam:env:docker:v1:0:beam:metric:sampled_byte_size:v1
 {PCOLLECTION=ref_PCollection_PCollection_15}: DistributionResult{sum=51, 
count=3, min=17, max=17}))
[flink-runner-job-invoker] INFO 
org.apache.beam.runners.fnexecution.artifact.AbstractArtifactRetrievalService - 
Manifest at 
/tmp/beam-artifact-staging/job_67836b8e-77bb-4d4c-a12f-8c97be58470d/MANIFEST 
has 0 artifact locations
[flink-runner-job-invoker] INFO 
org.apache.beam.runners.fnexecution.artifact.BeamFileSystemArtifactStagingService
 - Removed dir 
/tmp/beam-artifact-staging/job_67836b8e-77bb-4d4c-a12f-8c97be58470d/
INFO:apache_beam.runners.portability.portable_runner:Job state changed to DONE

> Task :sdks:python:test-suites:portable:py2:crossLanguageTests

FAILURE: Build completed with 2 failures.

1: Task failed with an exception.
-----------
* What went wrong:
Execution failed for task 
':sdks:python:test-suites:portable:py2:installGcpTest'.
> Process 'command 'sh'' finished with non-zero exit value 1

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

2: Task failed with an exception.
-----------
* Where:
Build file 
'<https://builds.apache.org/job/beam_PostCommit_Python2/ws/src/sdks/python/test-suites/dataflow/py2/build.gradle'>
 line: 85

* What went wrong:
Execution failed for task ':sdks:python:test-suites:dataflow:py2:postCommitIT'.
> Process 'command 'sh'' finished with non-zero exit value 1

* Try:
Run with --stacktrace option to get the stack trace. Run with --info or --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 6.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See 
https://docs.gradle.org/5.2.1/userguide/command_line_interface.html#sec:command_line_warnings

BUILD FAILED in 10m 20s
117 actionable tasks: 91 executed, 23 from cache, 3 up-to-date

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

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

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Build failed in Jenkins: beam_PostCommit_Python2 #1060

Reply via email to