[
https://issues.apache.org/jira/browse/OAK-4738?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15471984#comment-15471984
]
Andrei Dulceanu commented on OAK-4738:
--------------------------------------
Adding a detailed stacktrace for
{{FailoverIPRangeTest.testFailoverCorrectList}}:
{code:java}
23:30:43.068 INFO [main] FileStoreBuilder.java:340 Creating file store
FileStoreBuilder{directory=target/junit1211968823006721809/server,
blobStore=null, maxFileSize=1, segmentCacheSize=0, stringCacheSize=0,
templateCacheSize=0, stringDeduplicationCacheSize=15000,
templateDeduplicationCacheSize=3000, nodeDeduplicationCacheSize=0,
nodeDeduplicationCacheDepth=20, memoryMapping=false,
gcOptions=SegmentGCOptions{paused=false, memoryThreshold=5, gainThreshold=10,
retryCount=5, forceAfterFail=false, lockWaitTime=60, retainedGenerations=2,
gcSizeDeltaEstimation=-1}}
23:30:43.084 DEBUG [main] CacheLIRS.java:170 Init #0,
module=SegmentCache, maxMemory=0, segmentCount=16, stackMoveDistance=16
23:30:43.087 DEBUG [main] CacheLIRS.java:170 Init #1,
module=String Cache, maxMemory=0, segmentCount=16, stackMoveDistance=16
23:30:43.088 DEBUG [main] CacheLIRS.java:170 Init #2,
module=Template Cache, maxMemory=0, segmentCount=16, stackMoveDistance=16
23:30:43.135 INFO [main] FileStore.java:391 TarMK opened:
target/junit1211968823006721809/server (mmap=false)
23:30:43.135 DEBUG [main] FileStore.java:393 TarMK readers []
23:30:43.169 DEBUG [main] SegmentBufferWriter.java:381 Writing data
segment:
id=ea9fb9d8-d579-4cbf-a63b-8ae801d4bf83,size=208,segmentIdCount=0,recordIdCount=6,recordCount=6
23:30:43.170 DEBUG [main] TarWriter.java:236 Writing segment
ea9fb9d8-d579-4cbf-a63b-8ae801d4bf83 to
target/junit1211968823006721809/server/data00000a.tar
23:30:43.172 INFO [main] FileStoreBuilder.java:340 Creating file store
FileStoreBuilder{directory=target/junit1211968823006721809/client-1,
blobStore=null, maxFileSize=1, segmentCacheSize=0, stringCacheSize=0,
templateCacheSize=0, stringDeduplicationCacheSize=15000,
templateDeduplicationCacheSize=3000, nodeDeduplicationCacheSize=0,
nodeDeduplicationCacheDepth=20, memoryMapping=false,
gcOptions=SegmentGCOptions{paused=false, memoryThreshold=5, gainThreshold=10,
retryCount=5, forceAfterFail=false, lockWaitTime=60, retainedGenerations=2,
gcSizeDeltaEstimation=-1}}
23:30:43.173 DEBUG [main] CacheLIRS.java:170 Init #3,
module=SegmentCache, maxMemory=0, segmentCount=16, stackMoveDistance=16
23:30:43.173 DEBUG [main] CacheLIRS.java:170 Init #4,
module=String Cache, maxMemory=0, segmentCount=16, stackMoveDistance=16
23:30:43.173 DEBUG [main] CacheLIRS.java:170 Init #5,
module=Template Cache, maxMemory=0, segmentCount=16, stackMoveDistance=16
23:30:43.174 INFO [main] FileStore.java:391 TarMK opened:
target/junit1211968823006721809/client-1 (mmap=false)
23:30:43.175 DEBUG [main] FileStore.java:393 TarMK readers []
23:30:43.176 DEBUG [main] SegmentBufferWriter.java:381 Writing data
segment:
id=9e7de185-eb00-48c2-af1d-d7fdade02b39,size=208,segmentIdCount=0,recordIdCount=6,recordCount=6
23:30:43.176 DEBUG [main] TarWriter.java:236 Writing segment
9e7de185-eb00-48c2-af1d-d7fdade02b39 to
target/junit1211968823006721809/client-1/data00000a.tar
23:30:43.179 INFO [main] SegmentNodeStore.java:107 Creating segment
node store SegmentNodeStoreBuilder{blobStore=inline}
23:30:43.195 DEBUG [main] Slf4JLogger.java:71 Using SLF4J as the
default logging framework
23:30:43.198 DEBUG [main] Slf4JLogger.java:76
java.nio.Buffer.address: available
23:30:43.199 DEBUG [main] Slf4JLogger.java:76
sun.misc.Unsafe.theUnsafe: available
23:30:43.199 DEBUG [main] Slf4JLogger.java:71
sun.misc.Unsafe.copyMemory: available
23:30:43.199 DEBUG [main] Slf4JLogger.java:76
java.nio.Bits.unaligned: true
23:30:43.205 DEBUG [main] Slf4JLogger.java:76 UID: 501
23:30:43.206 DEBUG [main] Slf4JLogger.java:76 Java version: 7
23:30:43.206 DEBUG [main] Slf4JLogger.java:76
-Dio.netty.noUnsafe: false
23:30:43.206 DEBUG [main] Slf4JLogger.java:76 sun.misc.Unsafe:
available
23:30:43.206 DEBUG [main] Slf4JLogger.java:76
-Dio.netty.noJavassist: false
23:30:43.208 DEBUG [main] Slf4JLogger.java:71 Javassist:
unavailable
23:30:43.208 DEBUG [main] Slf4JLogger.java:71 You don't have
Javassist in your class path or you don't have enough permission to load
dynamically generated classes. Please check the configuration for better
performance.
23:30:43.208 DEBUG [main] Slf4JLogger.java:76 -Dio.netty.tmpdir:
/var/folders/1s/pdyvv3kd33v2x1_fckz4v2pr0000gn/T (java.io.tmpdir)
23:30:43.209 DEBUG [main] Slf4JLogger.java:76 -Dio.netty.bitMode:
64 (sun.arch.data.model)
23:30:43.209 DEBUG [main] Slf4JLogger.java:76
-Dio.netty.noPreferDirect: false
23:30:43.212 DEBUG [main] Slf4JLogger.java:76
-Dio.netty.eventLoopThreads: 16
23:30:43.236 DEBUG [main] Slf4JLogger.java:76
-Dio.netty.noKeySetOptimization: false
23:30:43.237 DEBUG [main] Slf4JLogger.java:76
-Dio.netty.selectorAutoRebuildThreshold: 512
23:30:43.309 DEBUG [nioEventLoopGroup-2-1] Slf4JLogger.java:71
-Dio.netty.initialSeedUniquifier: 0x7c44033e90ff3aed (took 0 ms)
23:30:43.333 DEBUG [nioEventLoopGroup-2-1] Slf4JLogger.java:76
-Dio.netty.allocator.type: unpooled
23:30:43.333 DEBUG [nioEventLoopGroup-2-1] Slf4JLogger.java:76
-Dio.netty.threadLocalDirectBufferSize: 65536
23:30:43.335 DEBUG [nioEventLoopGroup-2-1] Slf4JLogger.java:86 Loopback
interface: lo0 (lo0, 0:0:0:0:0:0:0:1)
23:30:43.335 DEBUG [nioEventLoopGroup-2-1] Slf4JLogger.java:81
/proc/sys/net/core/somaxconn: 128 (non-existent)
23:30:43.350 DEBUG [main] SegmentBufferWriter.java:381 Writing data
segment:
id=4f8d42be-2748-4cde-aa3f-ae0b60875a91,size=336,segmentIdCount=1,recordIdCount=11,recordCount=9
23:30:43.351 DEBUG [main] TarWriter.java:236 Writing segment
4f8d42be-2748-4cde-aa3f-ae0b60875a91 to
target/junit1211968823006721809/server/data00000a.tar
23:30:43.354 DEBUG [main] TarRevisions.java:203 TarMK journal
update null -> 4f8d42be-2748-4cde-aa3f-ae0b60875a91.ffb7
------------Number of flushes to journal---------:1
23:30:43.380 DEBUG [nioEventLoopGroup-4-1] StandbyClientHandler.java:59 sending
head request
23:30:43.383 DEBUG [nioEventLoopGroup-4-1] Slf4JLogger.java:76
-Dio.netty.recycler.maxCapacity.default: 262144
23:30:43.389 DEBUG [nioEventLoopGroup-4-1] Slf4JLogger.java:81
-Dio.netty.leakDetectionLevel: simple
23:30:43.393 DEBUG [nioEventLoopGroup-4-1] Slf4JLogger.java:76
java.nio.ByteBuffer.cleaner(): available
23:30:43.393 DEBUG [nioEventLoopGroup-4-1] StandbyClientHandler.java:61 did
send head request
23:30:43.426 ERROR [nioEventLoopGroup-4-1] StandbyClientHandler.java:93
Exception caught, closing channel.
io.netty.handler.timeout.ReadTimeoutException: null
23:30:43.442 ERROR [nioEventLoopGroup-4-1] StandbyClientHandler.java:93
Exception caught, closing channel.
io.netty.handler.codec.DecoderException: java.io.IOException: Received
unexpected empty frame. Maybe you have enabled secure transmission on only one
endpoint of the connection.
at
io.netty.handler.codec.ByteToMessageDecoder.channelInactive(ByteToMessageDecoder.java:213)
[netty-codec-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:233)
[netty-transport-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.channel.AbstractChannelHandlerContext.fireChannelInactive(AbstractChannelHandlerContext.java:219)
[netty-transport-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.handler.codec.ByteToMessageDecoder.channelInactive(ByteToMessageDecoder.java:228)
[netty-codec-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:233)
[netty-transport-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.channel.AbstractChannelHandlerContext.fireChannelInactive(AbstractChannelHandlerContext.java:219)
[netty-transport-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.channel.ChannelInboundHandlerAdapter.channelInactive(ChannelInboundHandlerAdapter.java:75)
[netty-transport-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.handler.timeout.ReadTimeoutHandler.channelInactive(ReadTimeoutHandler.java:144)
[netty-handler-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:233)
[netty-transport-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.channel.AbstractChannelHandlerContext.fireChannelInactive(AbstractChannelHandlerContext.java:219)
[netty-transport-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.channel.DefaultChannelPipeline.fireChannelInactive(DefaultChannelPipeline.java:769)
[netty-transport-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.channel.AbstractChannel$AbstractUnsafe$5.run(AbstractChannel.java:567)
[netty-transport-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:380)
[netty-common-4.0.23.Final.jar:4.0.23.Final]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:357)
[netty-transport-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:116)
[netty-common-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.util.concurrent.DefaultThreadFactory$DefaultRunnableDecorator.run(DefaultThreadFactory.java:137)
[netty-common-4.0.23.Final.jar:4.0.23.Final]
at java.lang.Thread.run(Thread.java:745) [na:1.7.0_79]
Caused by: java.io.IOException: Received unexpected empty frame. Maybe you have
enabled secure transmission on only one endpoint of the connection.
at
org.apache.jackrabbit.oak.segment.standby.codec.RecordIdDecoder.decode(RecordIdDecoder.java:50)
~[classes/:na]
at
io.netty.handler.codec.LengthFieldBasedFrameDecoder.decode(LengthFieldBasedFrameDecoder.java:343)
~[netty-codec-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.handler.codec.ByteToMessageDecoder.decodeLast(ByteToMessageDecoder.java:304)
[netty-codec-4.0.23.Final.jar:4.0.23.Final]
at
io.netty.handler.codec.ByteToMessageDecoder.channelInactive(ByteToMessageDecoder.java:208)
[netty-codec-4.0.23.Final.jar:4.0.23.Final]
... 16 common frames omitted
23:30:44.273 DEBUG [nioEventLoopGroup-3-1] CommunicationObserver.java:136 got
message 'h' from client d072dca0-f4d2-4400-9bb5-c9f3e8d3132e
23:30:45.286 DEBUG [main] FileStore.java:1194 The compaction
background thread was successfully shut down
23:30:45.286 DEBUG [main] FileStore.java:1204 The flush
background thread was successfully shut down
23:30:45.287 DEBUG [main] FileStore.java:1214 The disk space
check background thread was successfully shut down
23:30:45.331 INFO [main] FileStore.java:1249 TarMK closed:
target/junit1211968823006721809/server
23:30:45.331 DEBUG [main] FileStore.java:1194 The compaction
background thread was successfully shut down
23:30:45.332 DEBUG [main] FileStore.java:1204 The flush
background thread was successfully shut down
23:30:45.332 DEBUG [main] FileStore.java:1214 The disk space
check background thread was successfully shut down
23:30:45.334 DEBUG [main] TarRevisions.java:203 TarMK journal
update null -> 9e7de185-eb00-48c2-af1d-d7fdade02b39.ffd5
23:30:45.365 INFO [main] FileStore.java:1249 TarMK closed:
target/junit1211968823006721809/client-1
{code}
> Fix unit tests in FailoverIPRangeTest
> -------------------------------------
>
> Key: OAK-4738
> URL: https://issues.apache.org/jira/browse/OAK-4738
> Project: Jackrabbit Oak
> Issue Type: Technical task
> Components: segment-tar
> Reporter: Andrei Dulceanu
> Assignee: Andrei Dulceanu
> Fix For: Segment Tar 0.0.20
>
>
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
