Hello everybody,
took another look at the job and found the log at
https://builds.apache.org/view/C/view/Apache%20Camel/job/Camel/job/master/244/execution/node/3/ws/components/camel-milo/target/
I forwarded the whole log directly to Jens.
The cause of the failure seems to be:
2018-04-26 23:00:47,835 [tilSecureRandom] INFO
NonceUtil - SecureRandom seeded in 7ms.
2018-04-26 23:00:47,954 [ty-event-loop-3] WARN
InsecureCertificateValidator - Skipping validation for certificate:
O=Milliways, L=End of the universe, C=XX
2018-04-26 23:00:47,954 [ty-event-loop-3] WARN
InsecureCertificateValidator - Skipping trust chain verification for
certificate: O=Milliways, L=End of the universe, C=XX
2018-04-26 23:00:48,007 [a-shared-pool-2] ERROR
UaTcpClientMessageHandler - Error encoding
CreateSessionRequest{RequestHeader=RequestHeader{AuthenticationToken=NodeId{ns=0,
id=0}, Timestamp=DateTime{utcTime=131692572479710000, javaDate=Thu Apr
26 23:00:47 UTC 2018}, RequestHandle=0, ReturnDiagnostics=0,
AuditEntryId=null, TimeoutHint=16000, AdditionalHeader=null},
ClientDescription=ApplicationDescription{ApplicationUri=http://camel.apache.org/EclipseMilo/Client,
ProductUri=http://camel.apache.org/EclipseMilo,
ApplicationName=LocalizedText{text=Apache Camel adapter for Eclipse
Milo, locale=en}, ApplicationType=Client, GatewayServerUri=null,
DiscoveryProfileUri=null, DiscoveryUrls=null}, ServerUri=null,
EndpointUrl=opc.tcp://localhost:39088, SessionName=UaSession:Apache
Camel adapter for Eclipse Milo:1524783647971,
ClientNonce=ByteString{bytes=[-81, -68, -20, -68, -58, 8, 23, 44, 21,
-68, 106, -122, 94, 42, 46, -74, 83, -89, 36, -73, -99, 114, 58, -48,
71, -95, -78, 15, -74, 7, -41, -33]},
ClientCertificate=ByteString{bytes=[48, -126, 3, 84, 48, -126, 2, 60,
-96, 3, 2, 1, 2, 2, 9, 0, -85, -15, 5, -27, 119, -85, 80, 44, 48, 13, 6,
9, 42, -122, 72, -122, -9, 13, 1, 1, 11, 5, 0, 48, 63, 49, 11, 48, 9, 6,
3, 85, 4, 6, 19, 2, 88, 88, 49, 28, 48, 26, 6, 3, 85, 4, 7, 19, 19, 69,
110, 100, 32, 111, 102, 32, 116, 104, 101, 32, 117, 110, 105, 118, 101,
114, 115, 101, 49, 18, 48, 16, 6, 3, 85, 4, 10, 19, 9, 77, 105, 108,
108, 105, 119, 97, 121, 115, 48, 32, 23, 13, 49, 54, 48, 55, 50, 49, 49,
51, 50, 56, 48, 48, 90, 24, 15, 50, 49, 49, 54, 48, 54, 50, 55, 49, 51,
50, 56, 48, 48, 90, 48, 63, 49, 11, 48, 9, 6, 3, 85, 4, 6, 19, 2, 88,
88, 49, 28, 48, 26, 6, 3, 85, 4, 7, 19, 19, 69, 110, 100, 32, 111, 102,
32, 116, 104, 101, 32, 117, 110, 105, 118, 101, 114, 115, 101, 49, 18,
48, 16, 6, 3, 85, 4, 10, 19, 9, 77, 105, 108, 108, 105, 119, 97, 121,
115, 48, -126, 1, 34, 48, 13, 6, 9, 42, -122, 72, -122, -9, 13, 1, 1, 1,
5, 0, 3, -126, 1, 15, 0, 48, -126, 1, 10, 2, -126, 1, 1, 0, -40, 33, 37,
6, -126, 8, -57, -1, 114, -82, 80, 114, -29, 82, -16, -42, 54, -119,
-126, 15, 51, 42, 76, 14, 16, -82, 82, -76, 115, 27, 116, 85, -102, -76,
14, 108, -49, -41, 17, 45, 2, 21, -55, -23, -27, -43, -80, 27, 31, 110,
-4, -42, 23, -16, 10, -42, -117, 64, 63, -82, -16, -80, 108, -7, -39,
-25, 91, -51, -56, 86, -47, 53, 111, 41, -35, -62, 0, -96, 71, -16, -79,
96, -55, -53, -69, -109, -90, -40, 117, -49, -76, 114, -72, 50, -59,
-29, 53, 53, 73, -126, -57, 89, -15, 109, -12, -52, 89, 32, -75, 9, 99,
-77, -115, -107, -50, 86, 90, -78, 80, -127, -40, -16, 114, 94, -76, -6,
73, -91, 45, 6, -117, 48, 31, 107, -25, -126, 29, -92, 87, 59, 32, -68,
-11, -43, 23, -47, -60, -68, -33, -67, 13, 109, -54, -97, -83, -117,
-37, 119, -62, -50, -94, -63, -53, 98, -94, 127, -57, -77, -5, 96, -116,
78, -109, 15, -16, -97, -103, -74, 56, 7, -110, 124, -117, 106, 124,
-48, -117, 109, 29, -66, -94, 32, -85, 44, -69, -12, 49, 116, 21, 10,
40, -104, 124, -14, -24, 17, -29, -59, 111, 111, 60, -4, 9, 32, 35, -3,
96, -93, -126, -20, -121, -81, -17, 6, 116, -44, -45, 122, -1, 96, -93,
53, 64, 117, 113, -54, 6, -79, -33, -30, 63, -11, -59, -23, -64, -39,
-107, -16, 124, -125, 54, -114, 75, -69, -33, 27, 2, 3, 1, 0, 1, -93,
81, 48, 79, 48, 9, 6, 3, 85, 29, 19, 4, 2, 48, 0, 48, 11, 6, 3, 85, 29,
15, 4, 4, 3, 2, 5, -96, 48, 53, 6, 3, 85, 29, 17, 4, 46, 48, 44, -122,
42, 104, 116, 116, 112, 58, 47, 47, 99, 97, 109, 101, 108, 46, 97, 112,
97, 99, 104, 101, 46, 111, 114, 103, 47, 69, 99, 108, 105, 112, 115,
101, 77, 105, 108, 111, 47, 67, 108, 105, 101, 110, 116, 48, 13, 6, 9,
42, -122, 72, -122, -9, 13, 1, 1, 11, 5, 0, 3, -126, 1, 1, 0, 90, -103,
113, -36, 68, 50, -105, -81, 87, -82, 126, -9, -65, -98, 108, 61, -81,
38, -108, 18, -116, -114, 50, 119, 22, 105, 104, 86, 69, 40, 96, -17,
36, -13, 23, 34, 60, 52, 71, 73, 86, -98, 41, -116, 20, -35, -45, -72,
52, 28, 15, -99, -70, 52, 36, -58, 86, 17, -90, 43, -11, 43, 21, 89,
-78, -47, -79, -6, -82, -34, -111, -83, 73, -63, -23, -18, 125, 70, 7,
-56, 36, -78, -38, 23, -35, -53, -86, -51, 81, 116, 81, 0, -92, 74, -57,
-44, 53, 113, -126, 125, 5, 80, 109, 12, 9, -125, -56, 65, 83, -66, -42,
77, -7, 67, 109, -54, -58, 49, -68, 95, 62, 4, -28, -64, -21, -4, -87,
-13, 99, 61, -84, 46, -30, 61, 57, -1, -44, -118, -95, -85, -118, -96,
-43, 80, -42, 113, -46, -20, 61, -19, -109, 58, 15, -58, 31, -76, 121,
-13, -95, -82, 19, -113, 94, -48, 127, 11, -36, -121, 64, -94, 12, 23,
59, 33, -46, -30, -27, -52, -42, 98, 49, 68, 99, 77, -102, 63, 106, -58,
125, 69, -48, -87, -107, 76, -35, -35, -56, -112, 13, -103, 58, 67, 21,
-68, 12, -118, -50, 127, 36, -108, 50, -27, -108, -43, 64, -38, -41, -6,
-128, -45, 14, -15, -125, -19, 90, -52, 20, 101, -34, -120, -108, -64,
-36, 78, -104, 113, -43, 121, 106, -88, -98, 22, -17, 98, 81, -66, 24,
111, -52, -115, 1, -36, -125, -83, -115, 30]},
RequestedSessionTimeout=120000.0, MaxResponseMessageSize=0}:
java.security.InvalidKeyException: Illegal key size
org.eclipse.milo.opcua.stack.core.UaException:
java.security.InvalidKeyException: Illegal key size
at
org.eclipse.milo.opcua.stack.core.channel.ChunkEncoder$SymmetricEncoder.getAndInitializeCipher(ChunkEncoder.java:409)
~[stack-core-0.2.1.jar:0.2.1]
at
org.eclipse.milo.opcua.stack.core.channel.ChunkEncoder$AbstractEncoder.encode(ChunkEncoder.java:215)
~[stack-core-0.2.1.jar:0.2.1]
at
org.eclipse.milo.opcua.stack.core.channel.ChunkEncoder.encode(ChunkEncoder.java:87)
~[stack-core-0.2.1.jar:0.2.1]
at
org.eclipse.milo.opcua.stack.core.channel.ChunkEncoder.encodeSymmetric(ChunkEncoder.java:73)
~[stack-core-0.2.1.jar:0.2.1]
at
org.eclipse.milo.opcua.stack.client.handlers.UaTcpClientMessageHandler.lambda$encode$5(UaTcpClientMessageHandler.java:344)
~[stack-client-0.2.1.jar:0.2.1]
at
org.eclipse.milo.opcua.stack.core.channel.SerializationQueue.lambda$encode$0(SerializationQueue.java:55)
~[stack-core-0.2.1.jar:0.2.1]
at
org.eclipse.milo.opcua.stack.core.util.ExecutionQueue$PollAndExecute.run(ExecutionQueue.java:107)
[stack-core-0.2.1.jar:0.2.1]
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[?:1.8.0_152]
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[?:1.8.0_152]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[?:1.8.0_152]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[?:1.8.0_152]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_152]
Caused by: java.security.InvalidKeyException: Illegal key size
at javax.crypto.Cipher.checkCryptoPerm(Cipher.java:1039) ~[?:1.8.0_122]
at javax.crypto.Cipher.implInit(Cipher.java:805) ~[?:1.8.0_122]
at javax.crypto.Cipher.chooseProvider(Cipher.java:864) ~[?:1.8.0_122]
at javax.crypto.Cipher.init(Cipher.java:1396) ~[?:1.8.0_122]
at javax.crypto.Cipher.init(Cipher.java:1327) ~[?:1.8.0_122]
at
org.eclipse.milo.opcua.stack.core.channel.ChunkEncoder$SymmetricEncoder.getAndInitializeCipher(ChunkEncoder.java:403)
~[stack-core-0.2.1.jar:0.2.1]
... 11 more
2018-04-26 23:00:48,076 [ty-event-loop-5] WARN
InsecureCertificateValidator - Skipping validation for certificate:
O=Milliways, L=End of the universe, C=XX
2018-04-26 23:00:48,077 [ty-event-loop-5] WARN
InsecureCertificateValidator - Skipping trust chain verification for
certificate: O=Milliways, L=End of the universe, C=XX
2018-04-26 23:01:04,026 [main ] INFO
SubscriptionManager - Failed to connect
java.util.concurrent.ExecutionException: UaException:
status=Bad_Timeout, message=request timed out after 16000ms
at
java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:357)
~[?:1.8.0_152]
at
java.util.concurrent.CompletableFuture.get(CompletableFuture.java:1895)
~[?:1.8.0_152]
at
org.apache.camel.component.milo.client.internal.SubscriptionManager.performConnect(SubscriptionManager.java:475)
~[classes/:?]
at
org.apache.camel.component.milo.client.internal.SubscriptionManager.performAndEvalConnect(SubscriptionManager.java:394)
[classes/:?]
at
org.apache.camel.component.milo.client.internal.SubscriptionManager.connect(SubscriptionManager.java:389)
[classes/:?]
at
org.apache.camel.component.milo.client.internal.SubscriptionManager.<init>(SubscriptionManager.java:359)
[classes/:?]
at
org.apache.camel.component.milo.client.MiloClientConnection.init(MiloClientConnection.java:49)
[classes/:?]
at
org.apache.camel.component.milo.client.MiloClientConnection.checkInit(MiloClientConnection.java:66)
[classes/:?]
at
org.apache.camel.component.milo.client.MiloClientConnection.monitorValue(MiloClientConnection.java:83)
[classes/:?]
at
org.apache.camel.component.milo.client.MiloClientConsumer.doStart(MiloClientConsumer.java:59)
[classes/:?]
at
org.apache.camel.support.ServiceSupport.start(ServiceSupport.java:61)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.startService(DefaultCamelContext.java:3705)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.doStartOrResumeRouteConsumers(DefaultCamelContext.java:4023)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.doStartRouteConsumers(DefaultCamelContext.java:3958)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.safelyStartRouteServices(DefaultCamelContext.java:3878)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.doStartOrResumeRoutes(DefaultCamelContext.java:3642)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.doStartCamel(DefaultCamelContext.java:3494)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.access$000(DefaultCamelContext.java:209)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext$2.call(DefaultCamelContext.java:3253)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext$2.call(DefaultCamelContext.java:3249)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.doWithDefinedClassLoader(DefaultCamelContext.java:3272)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.doStart(DefaultCamelContext.java:3249)
[classes/:?]
at
org.apache.camel.support.ServiceSupport.start(ServiceSupport.java:61)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.start(DefaultCamelContext.java:3165)
[classes/:?]
at
org.apache.camel.test.junit4.CamelTestSupport.startCamelContext(CamelTestSupport.java:721)
[classes/:?]
at
org.apache.camel.test.junit4.CamelTestSupport.doSetUp(CamelTestSupport.java:383)
[classes/:?]
at
org.apache.camel.test.junit4.CamelTestSupport.setUp(CamelTestSupport.java:272)
[classes/:?]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
~[?:1.8.0_152]
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
~[?:1.8.0_152]
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
~[?:1.8.0_152]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_152]
at
org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[junit-4.12.jar:4.12]
at
org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[junit-4.12.jar:4.12]
at
org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[junit-4.12.jar:4.12]
at
org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24)
[junit-4.12.jar:4.12]
at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
[junit-4.12.jar:4.12]
at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55)
[junit-4.12.jar:4.12]
at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55)
[junit-4.12.jar:4.12]
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
[junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
[junit-4.12.jar:4.12]
at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
[junit-4.12.jar:4.12]
at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
[junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
[junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
[junit-4.12.jar:4.12]
at
org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
[junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
[junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
[junit-4.12.jar:4.12]
at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
[junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
[junit-4.12.jar:4.12]
at
org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365)
[surefire-junit4-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273)
[surefire-junit4-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238)
[surefire-junit4-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159)
[surefire-junit4-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:379)
[surefire-booter-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:340)
[surefire-booter-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:125)
[surefire-booter-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:413)
[surefire-booter-2.21.0.jar:2.21.0]
Caused by: org.eclipse.milo.opcua.stack.core.UaException: request timed
out after 16000ms
at
org.eclipse.milo.opcua.stack.client.UaTcpStackClient.lambda$scheduleRequestTimeout$13(UaTcpStackClient.java:326)
~[stack-client-0.2.1.jar:0.2.1]
at
io.netty.util.HashedWheelTimer$HashedWheelTimeout.expire(HashedWheelTimer.java:581)
~[netty-common-4.0.36.Final.jar:4.0.36.Final]
at
io.netty.util.HashedWheelTimer$HashedWheelBucket.expireTimeouts(HashedWheelTimer.java:655)
~[netty-common-4.0.36.Final.jar:4.0.36.Final]
at
io.netty.util.HashedWheelTimer$Worker.run(HashedWheelTimer.java:367)
~[netty-common-4.0.36.Final.jar:4.0.36.Final]
at java.lang.Thread.run(Thread.java:748) ~[?:1.8.0_152]
2018-04-26 23:01:04,029 [main ] INFO
SubscriptionManager - Trigger re-connect (immediate: false)
2018-04-26 23:01:04,034 [main ] INFO
DefaultCamelContext - Route: route2 started and consuming
from:
milo-client://tcp://foo:xxxxxx@localhost:39088?discoveryEndpointSuffix=%2Fdiscovery&keyPassword=xxxxxx&keyStorePassword=xxxxxx&keyStoreUrl=file%3Asrc%2Ftest%2Fresources%2Fcert%2Fcert.p12&node=RAW(nsu=urn:org:apache:camel;s=items-myitem1)&overrideHost=true
2018-04-26 23:01:04,034 [main ] INFO
SubscriptionManager - Starting connect
2018-04-26 23:01:04,078 [ty-event-loop-9] WARN
ChannelInitializer - Failed to initialize a channel.
Closing: [id: 0xd5fe590e]
org.eclipse.milo.opcua.stack.core.UaException: no KeyPair configured
at
org.eclipse.milo.opcua.stack.client.UaTcpStackClient$1.lambda$initChannel$2(UaTcpStackClient.java:447)
~[stack-client-0.2.1.jar:0.2.1]
at java.util.Optional.orElseThrow(Optional.java:290) ~[?:1.8.0_152]
at
org.eclipse.milo.opcua.stack.client.UaTcpStackClient$1.initChannel(UaTcpStackClient.java:446)
~[stack-client-0.2.1.jar:0.2.1]
at
org.eclipse.milo.opcua.stack.client.UaTcpStackClient$1.initChannel(UaTcpStackClient.java:416)
~[stack-client-0.2.1.jar:0.2.1]
at
io.netty.channel.ChannelInitializer.channelRegistered(ChannelInitializer.java:68)
~[netty-transport-4.0.36.Final.jar:4.0.36.Final]
at
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRegistered(AbstractChannelHandlerContext.java:118)
[netty-transport-4.0.36.Final.jar:4.0.36.Final]
at
io.netty.channel.AbstractChannelHandlerContext.fireChannelRegistered(AbstractChannelHandlerContext.java:104)
[netty-transport-4.0.36.Final.jar:4.0.36.Final]
at
io.netty.channel.DefaultChannelPipeline.fireChannelRegistered(DefaultChannelPipeline.java:846)
[netty-transport-4.0.36.Final.jar:4.0.36.Final]
at
io.netty.channel.AbstractChannel$AbstractUnsafe.register0(AbstractChannel.java:453)
[netty-transport-4.0.36.Final.jar:4.0.36.Final]
at
io.netty.channel.AbstractChannel$AbstractUnsafe.access$200(AbstractChannel.java:370)
[netty-transport-4.0.36.Final.jar:4.0.36.Final]
at
io.netty.channel.AbstractChannel$AbstractUnsafe$1.run(AbstractChannel.java:420)
[netty-transport-4.0.36.Final.jar:4.0.36.Final]
at
io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:358)
[netty-common-4.0.36.Final.jar:4.0.36.Final]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:374)
[netty-transport-4.0.36.Final.jar:4.0.36.Final]
at
io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:112)
[netty-common-4.0.36.Final.jar:4.0.36.Final]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_152]
2018-04-26 23:01:04,081 [main ] INFO
SubscriptionManager - Failed to connect
java.util.concurrent.ExecutionException:
java.nio.channels.ClosedChannelException
at
java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:357)
~[?:1.8.0_152]
at
java.util.concurrent.CompletableFuture.get(CompletableFuture.java:1895)
~[?:1.8.0_152]
at
org.apache.camel.component.milo.client.internal.SubscriptionManager.performConnect(SubscriptionManager.java:475)
~[classes/:?]
at
org.apache.camel.component.milo.client.internal.SubscriptionManager.performAndEvalConnect(SubscriptionManager.java:394)
[classes/:?]
at
org.apache.camel.component.milo.client.internal.SubscriptionManager.connect(SubscriptionManager.java:389)
[classes/:?]
at
org.apache.camel.component.milo.client.internal.SubscriptionManager.<init>(SubscriptionManager.java:359)
[classes/:?]
at
org.apache.camel.component.milo.client.MiloClientConnection.init(MiloClientConnection.java:49)
[classes/:?]
at
org.apache.camel.component.milo.client.MiloClientConnection.checkInit(MiloClientConnection.java:66)
[classes/:?]
at
org.apache.camel.component.milo.client.MiloClientConnection.monitorValue(MiloClientConnection.java:83)
[classes/:?]
at
org.apache.camel.component.milo.client.MiloClientConsumer.doStart(MiloClientConsumer.java:59)
[classes/:?]
at
org.apache.camel.support.ServiceSupport.start(ServiceSupport.java:61)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.startService(DefaultCamelContext.java:3705)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.doStartOrResumeRouteConsumers(DefaultCamelContext.java:4023)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.doStartRouteConsumers(DefaultCamelContext.java:3958)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.safelyStartRouteServices(DefaultCamelContext.java:3878)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.doStartOrResumeRoutes(DefaultCamelContext.java:3642)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.doStartCamel(DefaultCamelContext.java:3494)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.access$000(DefaultCamelContext.java:209)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext$2.call(DefaultCamelContext.java:3253)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext$2.call(DefaultCamelContext.java:3249)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.doWithDefinedClassLoader(DefaultCamelContext.java:3272)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.doStart(DefaultCamelContext.java:3249)
[classes/:?]
at
org.apache.camel.support.ServiceSupport.start(ServiceSupport.java:61)
[classes/:?]
at
org.apache.camel.impl.DefaultCamelContext.start(DefaultCamelContext.java:3165)
[classes/:?]
at
org.apache.camel.test.junit4.CamelTestSupport.startCamelContext(CamelTestSupport.java:721)
[classes/:?]
at
org.apache.camel.test.junit4.CamelTestSupport.doSetUp(CamelTestSupport.java:383)
[classes/:?]
at
org.apache.camel.test.junit4.CamelTestSupport.setUp(CamelTestSupport.java:272)
[classes/:?]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
~[?:1.8.0_152]
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
~[?:1.8.0_152]
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
~[?:1.8.0_152]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_152]
at
org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[junit-4.12.jar:4.12]
at
org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[junit-4.12.jar:4.12]
at
org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[junit-4.12.jar:4.12]
at
org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24)
[junit-4.12.jar:4.12]
at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
[junit-4.12.jar:4.12]
at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55)
[junit-4.12.jar:4.12]
at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55)
[junit-4.12.jar:4.12]
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
[junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
[junit-4.12.jar:4.12]
at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
[junit-4.12.jar:4.12]
at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
[junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
[junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
[junit-4.12.jar:4.12]
at
org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
[junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
[junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
[junit-4.12.jar:4.12]
at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
[junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
[junit-4.12.jar:4.12]
at
org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365)
[surefire-junit4-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273)
[surefire-junit4-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238)
[surefire-junit4-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159)
[surefire-junit4-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:379)
[surefire-booter-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:340)
[surefire-booter-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:125)
[surefire-booter-2.21.0.jar:2.21.0]
at
org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:413)
[surefire-booter-2.21.0.jar:2.21.0]
Caused by: java.nio.channels.ClosedChannelException
Cheers,
Pascal
Am 24.04.2018 um 12:47 schrieb Pascal Schumacher:
Hi Jens,
thanks for lookin into this!
Here is the camel-milo part of the console output of the build:
[INFO] --------------------< org.apache.camel:camel-milo >---------------------
[INFO] Building Camel :: Milo 2.22.0-SNAPSHOT [201/765]
[INFO] --------------------------------[ jar ]---------------------------------
[INFO]
[INFO] --- maven-enforcer-plugin:1.4.1:enforce (enforce-maven) @ camel-milo ---
[INFO]
[INFO] --- maven-bundle-plugin:3.5.0:cleanVersions (versions) @ camel-milo ---
[INFO]
[INFO] --- maven-remote-resources-plugin:1.5:process (process-resource-bundles)
@ camel-milo ---
[INFO]
[INFO] --- camel-package-maven-plugin:2.22.0-SNAPSHOT:prepare-components
(prepare) @ camel-milo ---
[INFO]
[INFO] --- maven-resources-plugin:3.0.2:resources (default-resources) @
camel-milo ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 5 resources
[INFO] Copying 3 resources
[INFO] Copying 1 resource
[INFO] skip non existing resourceDirectory
/home/jenkins/jenkins-slave/workspace/Camel_master-CIGY56HHEDNGVJBWJRRHBZ64GKNJTCSYDKOPJS46W5IZTMAS3I2Q/components/camel-milo/target/generated/camel/dataformats
[INFO] skip non existing resourceDirectory
/home/jenkins/jenkins-slave/workspace/Camel_master-CIGY56HHEDNGVJBWJRRHBZ64GKNJTCSYDKOPJS46W5IZTMAS3I2Q/components/camel-milo/target/generated/camel/languages
[INFO]
[INFO] --- maven-compiler-plugin:3.7.0:compile (default-compile) @ camel-milo
---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 21 source files to
/home/jenkins/jenkins-slave/workspace/Camel_master-CIGY56HHEDNGVJBWJRRHBZ64GKNJTCSYDKOPJS46W5IZTMAS3I2Q/components/camel-milo/target/classes
[INFO]
[INFO] --- maven-resources-plugin:3.0.2:testResources (default-testResources) @
camel-milo ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 7 resources
[INFO] Copying 3 resources
[INFO]
[INFO] --- maven-compiler-plugin:3.7.0:testCompile (default-testCompile) @
camel-milo ---
[INFO] Nothing to compile - all classes are up to date
[INFO]
[INFO] --- maven-surefire-plugin:2.21.0:test (default-test) @ camel-milo ---
[INFO] Surefire report directory:
/home/jenkins/jenkins-slave/workspace/Camel_master-CIGY56HHEDNGVJBWJRRHBZ64GKNJTCSYDKOPJS46W5IZTMAS3I2Q/components/camel-milo/target/surefire-reports
[INFO]
[INFO] -------------------------------------------------------
[INFO] T E S T S
[INFO] -------------------------------------------------------
[INFO] Running
org.apache.camel.component.milo.MonitorItemMultiConnectionsCertTest
[ERROR] Tests run: 3, Failures: 3, Errors: 0, Skipped: 0, Time elapsed: 115.399 s
<<< FAILURE! - in
org.apache.camel.component.milo.MonitorItemMultiConnectionsCertTest
[ERROR]
testMonitorItem1(org.apache.camel.component.milo.MonitorItemMultiConnectionsCertTest)
Time elapsed: 41.293 s <<< FAILURE!
java.lang.AssertionError: mock://test1 Received message count. Expected: <1> but was:
<0>
at
org.apache.camel.component.milo.MonitorItemMultiConnectionsCertTest.testMonitorItem1(MonitorItemMultiConnectionsCertTest.java:130)
[ERROR]
testMonitorItem1(org.apache.camel.component.milo.MonitorItemMultiConnectionsCertTest)
Time elapsed: 36.976 s <<< FAILURE!
java.lang.AssertionError: mock://test1 Received message count. Expected: <1> but was:
<0>
at
org.apache.camel.component.milo.MonitorItemMultiConnectionsCertTest.testMonitorItem1(MonitorItemMultiConnectionsCertTest.java:130)
[ERROR]
testMonitorItem1(org.apache.camel.component.milo.MonitorItemMultiConnectionsCertTest)
Time elapsed: 36.578 s <<< FAILURE!
java.lang.AssertionError: mock://test1 Received message count. Expected: <1> but was:
<0>
at
org.apache.camel.component.milo.MonitorItemMultiConnectionsCertTest.testMonitorItem1(MonitorItemMultiConnectionsCertTest.java:130)
[INFO] Running org.apache.camel.component.milo.MonitorItemMultiConnectionsTest
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 15.389 s
- in org.apache.camel.component.milo.MonitorItemMultiConnectionsTest
[INFO] Running org.apache.camel.component.milo.MonitorItemTest
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 10.829 s
- in org.apache.camel.component.milo.MonitorItemTest
[INFO] Running org.apache.camel.component.milo.WriteClientTest
[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.566 s
- in org.apache.camel.component.milo.WriteClientTest
[INFO] Running org.apache.camel.component.milo.call.CallClientTest
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.959 s
- in org.apache.camel.component.milo.call.CallClientTest
[INFO] Running org.apache.camel.component.milo.client.NodeIdTest
[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.44 s -
in org.apache.camel.component.milo.client.NodeIdTest
[INFO] Running org.apache.camel.component.milo.converter.ConverterTest
[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.987 s
- in org.apache.camel.component.milo.converter.ConverterTest
[INFO] Running org.apache.camel.component.milo.server.ServerLocalTest
[INFO] Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.76 s -
in org.apache.camel.component.milo.server.ServerLocalTest
[INFO] Running
org.apache.camel.component.milo.server.ServerSetCertificateManagerTest
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.935 s
- in org.apache.camel.component.milo.server.ServerSetCertificateManagerTest
[INFO] Running
org.apache.camel.component.milo.server.ServerSetSecurityPoliciesTest
[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.755 s
- in org.apache.camel.component.milo.server.ServerSetSecurityPoliciesTest
[INFO]
[INFO] Results:
[INFO]
[ERROR] Failures:
[ERROR]
org.apache.camel.component.milo.MonitorItemMultiConnectionsCertTest.testMonitorItem1(org.apache.camel.component.milo.MonitorItemMultiConnectionsCertTest)
[ERROR] Run 1:
MonitorItemMultiConnectionsCertTest.testMonitorItem1:130->CamelTestSupport.assertMockEndpointsSatisfied:920
mock://test1 Received message count. Expected: <1> but was: <0>
[ERROR] Run 2:
MonitorItemMultiConnectionsCertTest.testMonitorItem1:130->CamelTestSupport.assertMockEndpointsSatisfied:920
mock://test1 Received message count. Expected: <1> but was: <0>
[ERROR] Run 3:
MonitorItemMultiConnectionsCertTest.testMonitorItem1:130->CamelTestSupport.assertMockEndpointsSatisfied:920
mock://test1 Received message count. Expected: <1> but was: <0>
[INFO]
[INFO]
[ERROR] Tests run: 27, Failures: 1, Errors: 0, Skipped: 0
Taken from
https://builds.apache.org/view/C/view/Apache%20Camel/job/Camel/job/master/245/consoleFull
I am not sure if there are further logs (if there are I do not have access rights).
Cheers,
Pascal
Gesendet: Dienstag, 24. April 2018 um 10:44 Uhr
Von: "Jens Reimann" <jreim...@redhat.com>
An: dev@camel.apache.org
Betreff: Re: Milo test cases disabled
Hi Pascal,
thanks for your help.
As I don't have access to the resources of the build job, would it be
possible for your to send me the log files of the test.
Thanks
Jens
On Mon, Apr 23, 2018 at 7:26 PM, Pascal Schumacher <pascalschumac...@gmx.net
wrote:
Hi Jens,
I disabled the test because at the time it was the only camel test that
always failed on builds.apache.org (with an assertion error).
Sadly I could not replicate the failure locally.
Currently there are no build results on builds.apache.org with the
assertion enabled.
I have just reverted the commit so you can check the tests results of the
camel-milo component on https://builds.apache.org/view
/C/view/Apache%20Camel/job/Camel/job/master/ in 6+ hours for details.
Cheers,
Pascal
Am 23.04.2018 um 16:42 schrieb Jens Reimann:
Hi,
I just saw that some of the Milo unit tests got disabled in a recent
commit:
->
https://github.com/apache/camel/commit/6cdfae2c1db963ab7cc07[https://github.com/apache/camel/commit/6cdfae2c1db963ab7cc07]
e43bec2c4495bc6914c
The commit just says:
camel-milo: Disable MonitorItemMultiConnectionsCertTest because it
always
fails on builds.apache.org
I would like to fix this rather than to disable this. So any information
about that would be helpful to me.
Cheers
Jens
--
Jens Reimann
Senior Software Engineer / EMEA ENG Middleware
Werner-von-Siemens-Ring 14
85630 Grasbrunn
Germany
phone: +49 89 2050 71286
_____________________________________________________________________________
Red Hat GmbH, www.de.redhat.com[http://www.de.redhat.com],
Registered seat: Grasbrunn, Commercial register: Amtsgericht Muenchen, HRB
153243,
Managing Directors: Paul Argiry, Charles Cachera, Michael Cunningham,
Michael O'Neill
