022-01-30T04:23:34.0985745Z Jan 30 04:23:34 [ERROR] Tests run: 8, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 146.275 s <<< FAILURE! - in org.apache.flink.connector.pulsar.source.PulsarSourceITCase
2022-01-30T04:23:34.1007447Z Jan 30 04:23:34 [ERROR] testTaskManagerFailure{TestEnvironment, ExternalContext, ClusterControllable}[2] Time elapsed: 24.029 s <<< FAILURE!
2022-01-30T04:23:34.1008053Z Jan 30 04:23:34 java.lang.AssertionError:
2022-01-30T04:23:34.1008387Z Jan 30 04:23:34
2022-01-30T04:23:34.1016908Z Jan 30 04:23:34 Expected: Records consumed by Flink should be identical to test data and preserve the order in split
2022-01-30T04:23:34.1018207Z Jan 30 04:23:34 but: Mismatched record at position 35: Expected '0-Xyr234aDNWfV4C55F3Wvxcsm3EkA0Egx3adKqxG' but was '0-iFyyNpKte8Lu4wWK8NPz1BFSoc0XYI5yqnemcNr8Wv7avyQqP'
2022-01-30T04:23:34.1018923Z Jan 30 04:23:34 at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
2022-01-30T04:23:34.1019506Z Jan 30 04:23:34 at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:8)
2022-01-30T04:23:34.1020251Z Jan 30 04:23:34 at org.apache.flink.connectors.test.common.testsuites.SourceTestSuiteBase.testTaskManagerFailure(SourceTestSuiteBase.java:289)
2022-01-30T04:23:34.1020934Z Jan 30 04:23:34 at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2022-01-30T04:23:34.1021596Z Jan 30 04:23:34 at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2022-01-30T04:23:34.1022260Z Jan 30 04:23:34 at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2022-01-30T04:23:34.1022853Z Jan 30 04:23:34 at java.lang.reflect.Method.invoke(Method.java:498)
2022-01-30T04:23:34.1023453Z Jan 30 04:23:34 at org.junit.platform.commons.util.ReflectionUtils.invokeMethod(ReflectionUtils.java:688)
2022-01-30T04:23:34.1024245Z Jan 30 04:23:34 at org.junit.jupiter.engine.execution.MethodInvocation.proceed(MethodInvocation.java:60)
2022-01-30T04:23:34.1025020Z Jan 30 04:23:34 at org.junit.jupiter.engine.execution.InvocationInterceptorChain$ValidatingInvocation.proceed(InvocationInterceptorChain.java:131)
2022-01-30T04:23:34.1026041Z Jan 30 04:23:34 at org.junit.jupiter.engine.extension.TimeoutExtension.intercept(TimeoutExtension.java:149)
2022-01-30T04:23:34.1026766Z Jan 30 04:23:34 at org.junit.jupiter.engine.extension.TimeoutExtension.interceptTestableMethod(TimeoutExtension.java:140)
2022-01-30T04:23:34.1027523Z Jan 30 04:23:34 at org.junit.jupiter.engine.extension.TimeoutExtension.interceptTestTemplateMethod(TimeoutExtension.java:92)
2022-01-30T04:23:34.1028326Z Jan 30 04:23:34 at org.junit.jupiter.engine.execution.ExecutableInvoker$ReflectiveInterceptorCall.lambda$ofVoidMethod$0(ExecutableInvoker.java:115)
2022-01-30T04:23:34.1029128Z Jan 30 04:23:34 at org.junit.jupiter.engine.execution.ExecutableInvoker.lambda$invoke$0(ExecutableInvoker.java:105)
2022-01-30T04:23:34.1029926Z Jan 30 04:23:34 at org.junit.jupiter.engine.execution.InvocationInterceptorChain$InterceptedInvocation.proceed(InvocationInterceptorChain.java:106)
2022-01-30T04:23:34.1030893Z Jan 30 04:23:34 at org.junit.jupiter.engine.execution.InvocationInterceptorChain.proceed(InvocationInterceptorChain.java:64)
2022-01-30T04:23:34.1031683Z Jan 30 04:23:34 at org.junit.jupiter.engine.execution.InvocationInterceptorChain.chainAndInvoke(InvocationInterceptorChain.java:45)
2022-01-30T04:23:34.1032466Z Jan 30 04:23:34 at org.junit.jupiter.engine.execution.InvocationInterceptorChain.invoke(InvocationInterceptorChain.java:37)
2022-01-30T04:23:34.1033188Z Jan 30 04:23:34 at org.junit.jupiter.engine.execution.ExecutableInvoker.invoke(ExecutableInvoker.java:104)
2022-01-30T04:23:34.1033870Z Jan 30 04:23:34 at org.junit.jupiter.engine.execution.ExecutableInvoker.invoke(ExecutableInvoker.java:98)
2022-01-30T04:23:34.1034612Z Jan 30 04:23:34 at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.lambda$invokeTestMethod$6(TestMethodTestDescriptor.java:210)
2022-01-30T04:23:34.1035407Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
2022-01-30T04:23:34.1036188Z Jan 30 04:23:34 at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.invokeTestMethod(TestMethodTestDescriptor.java:206)
2022-01-30T04:23:34.1036961Z Jan 30 04:23:34 at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:131)
2022-01-30T04:23:34.1037709Z Jan 30 04:23:34 at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:65)
2022-01-30T04:23:34.1038476Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:139)
2022-01-30T04:23:34.1039247Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
2022-01-30T04:23:34.1039993Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$7(NodeTestTask.java:129)
2022-01-30T04:23:34.1040709Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:137)
2022-01-30T04:23:34.1041411Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:127)
2022-01-30T04:23:34.1042179Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
2022-01-30T04:23:34.1042917Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:126)
2022-01-30T04:23:34.1043634Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:84)
2022-01-30T04:23:34.1044450Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService.submit(SameThreadHierarchicalTestExecutorService.java:32)
2022-01-30T04:23:34.1045333Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask$DefaultDynamicTestExecutor.execute(NodeTestTask.java:212)
2022-01-30T04:23:34.1046214Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask$DefaultDynamicTestExecutor.execute(NodeTestTask.java:192)
2022-01-30T04:23:34.1047045Z Jan 30 04:23:34 at org.junit.jupiter.engine.descriptor.TestTemplateTestDescriptor.execute(TestTemplateTestDescriptor.java:139)
2022-01-30T04:23:34.1047841Z Jan 30 04:23:34 at org.junit.jupiter.engine.descriptor.TestTemplateTestDescriptor.lambda$execute$2(TestTemplateTestDescriptor.java:107)
2022-01-30T04:23:34.1048559Z Jan 30 04:23:34 at java.util.stream.ForEachOps$ForEachOp$OfRef.accept(ForEachOps.java:183)
2022-01-30T04:23:34.1049183Z Jan 30 04:23:34 at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193)
2022-01-30T04:23:34.1049804Z Jan 30 04:23:34 at java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:175)
2022-01-30T04:23:34.1050424Z Jan 30 04:23:34 at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193)
2022-01-30T04:23:34.1051094Z Jan 30 04:23:34 at java.util.stream.ForEachOps$ForEachOp$OfRef.accept(ForEachOps.java:183)
2022-01-30T04:23:34.1051716Z Jan 30 04:23:34 at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193)
2022-01-30T04:23:34.1052353Z Jan 30 04:23:34 at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1384)
2022-01-30T04:23:34.1063833Z Jan 30 04:23:34 at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:482)
2022-01-30T04:23:34.1064496Z Jan 30 04:23:34 at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:472)
2022-01-30T04:23:34.1065139Z Jan 30 04:23:34 at java.util.stream.ForEachOps$ForEachOp.evaluateSequential(ForEachOps.java:150)
2022-01-30T04:23:34.1065786Z Jan 30 04:23:34 at java.util.stream.ForEachOps$ForEachOp$OfRef.evaluateSequential(ForEachOps.java:173)
2022-01-30T04:23:34.1066423Z Jan 30 04:23:34 at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
2022-01-30T04:23:34.1067049Z Jan 30 04:23:34 at java.util.stream.ReferencePipeline.forEach(ReferencePipeline.java:485)
2022-01-30T04:23:34.1067670Z Jan 30 04:23:34 at java.util.stream.ReferencePipeline$7$1.accept(ReferencePipeline.java:272)
2022-01-30T04:23:34.1068306Z Jan 30 04:23:34 at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1384)
2022-01-30T04:23:34.1068936Z Jan 30 04:23:34 at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:482)
2022-01-30T04:23:34.1069559Z Jan 30 04:23:34 at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:472)
2022-01-30T04:23:34.1070183Z Jan 30 04:23:34 at java.util.stream.ForEachOps$ForEachOp.evaluateSequential(ForEachOps.java:150)
2022-01-30T04:23:34.1070838Z Jan 30 04:23:34 at java.util.stream.ForEachOps$ForEachOp$OfRef.evaluateSequential(ForEachOps.java:173)
2022-01-30T04:23:34.1071472Z Jan 30 04:23:34 at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
2022-01-30T04:23:34.1072083Z Jan 30 04:23:34 at java.util.stream.ReferencePipeline.forEach(ReferencePipeline.java:485)
2022-01-30T04:23:34.1072782Z Jan 30 04:23:34 at org.junit.jupiter.engine.descriptor.TestTemplateTestDescriptor.execute(TestTemplateTestDescriptor.java:107)
2022-01-30T04:23:34.1073556Z Jan 30 04:23:34 at org.junit.jupiter.engine.descriptor.TestTemplateTestDescriptor.execute(TestTemplateTestDescriptor.java:42)
2022-01-30T04:23:34.1074332Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:139)
2022-01-30T04:23:34.1075084Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
2022-01-30T04:23:34.1075845Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$7(NodeTestTask.java:129)
2022-01-30T04:23:34.1076550Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:137)
2022-01-30T04:23:34.1077410Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:127)
2022-01-30T04:23:34.1078170Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
2022-01-30T04:23:34.1078912Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:126)
2022-01-30T04:23:34.1079634Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:84)
2022-01-30T04:23:34.1080223Z Jan 30 04:23:34 at java.util.ArrayList.forEach(ArrayList.java:1259)
2022-01-30T04:23:34.1080963Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService.invokeAll(SameThreadHierarchicalTestExecutorService.java:38)
2022-01-30T04:23:34.1081838Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:143)
2022-01-30T04:23:34.1082683Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
2022-01-30T04:23:34.1083447Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$7(NodeTestTask.java:129)
2022-01-30T04:23:34.1084151Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:137)
2022-01-30T04:23:34.1084850Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:127)
2022-01-30T04:23:34.1085597Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
2022-01-30T04:23:34.1086341Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:126)
2022-01-30T04:23:34.1087058Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:84)
2022-01-30T04:23:34.1087666Z Jan 30 04:23:34 at java.util.ArrayList.forEach(ArrayList.java:1259)
2022-01-30T04:23:34.1088403Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService.invokeAll(SameThreadHierarchicalTestExecutorService.java:38)
2022-01-30T04:23:34.1089275Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:143)
2022-01-30T04:23:34.1090034Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
2022-01-30T04:23:34.1090774Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$7(NodeTestTask.java:129)
2022-01-30T04:23:34.1091532Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:137)
2022-01-30T04:23:34.1092238Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:127)
2022-01-30T04:23:34.1093005Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
2022-01-30T04:23:34.1093745Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:126)
2022-01-30T04:23:34.1094458Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:84)
2022-01-30T04:23:34.1095274Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService.submit(SameThreadHierarchicalTestExecutorService.java:32)
2022-01-30T04:23:34.1096873Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.HierarchicalTestExecutor.execute(HierarchicalTestExecutor.java:57)
2022-01-30T04:23:34.1097673Z Jan 30 04:23:34 at org.junit.platform.engine.support.hierarchical.HierarchicalTestEngine.execute(HierarchicalTestEngine.java:51)
2022-01-30T04:23:34.1098499Z Jan 30 04:23:34 at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:220)
2022-01-30T04:23:34.1099186Z Jan 30 04:23:34 at org.junit.platform.launcher.core.DefaultLauncher.lambda$execute$6(DefaultLauncher.java:188)
2022-01-30T04:23:34.1099900Z Jan 30 04:23:34 at org.junit.platform.launcher.core.DefaultLauncher.withInterceptedStreams(DefaultLauncher.java:202)
2022-01-30T04:23:34.1100598Z Jan 30 04:23:34 at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:181)
2022-01-30T04:23:34.1101263Z Jan 30 04:23:34 at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:128)
2022-01-30T04:23:34.1101989Z Jan 30 04:23:34 at org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.invokeAllTests(JUnitPlatformProvider.java:150)
2022-01-30T04:23:34.1102755Z Jan 30 04:23:34 at org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.invoke(JUnitPlatformProvider.java:120)
2022-01-30T04:23:34.1103485Z Jan 30 04:23:34 at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384)
2022-01-30T04:23:34.1104278Z Jan 30 04:23:34 at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345)
2022-01-30T04:23:34.1104939Z Jan 30 04:23:34 at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126)
2022-01-30T04:23:34.1105568Z Jan 30 04:23:34 at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)
2022-01-30T04:23:34.1106019Z Jan 30 04:23:34
2022-01-30T04:23:34.4899680Z Jan 30 04:23:34 [INFO]
2022-01-30T04:23:34.4900167Z Jan 30 04:23:34 [INFO] Results:
2022-01-30T04:23:34.4900482Z Jan 30 04:23:34 [INFO]
2022-01-30T04:23:34.4900804Z Jan 30 04:23:34 [ERROR] Failures:
2022-01-30T04:23:34.4901289Z Jan 30 04:23:34 [ERROR] PulsarSourceITCase>SourceTestSuiteBase.testTaskManagerFailure:289
2022-01-30T04:23:34.4901911Z Jan 30 04:23:34 Expected: Records consumed by Flink should be identical to test data and preserve the order in split
2022-01-30T04:23:34.4903469Z Jan 30 04:23:34 but: Mismatched record at position 35: Expected '0-Xyr234aDNWfV4C55F3Wvxcsm3EkA0Egx3adKqxG' but was '0-iFyyNpKte8Lu4wWK8NPz1BFSoc0XYI5yqnemcNr8Wv7avyQqP'
2022-01-30T04:23:34.4904055Z Jan 30 04:23:34 [INFO]
2022-01-30T04:23:34.4904429Z Jan 30 04:23:34 [ERROR] Tests run: 8, Failures: 1, Errors: 0, Skipped: 0
Sub-task
Minor
FLINK-27399 Pulsar connector didn't set start consuming position correctly
