Open deepakjaju opened 6 years ago
2018-06-12 10:01:51.340431] == Executing bash with arguments [/opt/cisco/kinetic/efm_server/dslinks/opc/bin/dslink-java-opc, --name, OPC, --nodes, nodes.json, --key, .key, --log, debug, --broker, http://127.0.0.1:46021/conn, --token, 3VGXkGJxxDETmp89urlmdZG8IsknRbooTubzyjrc5YwddTsB] (pid: 11177) == [2018-06-12 10:01:51.354732] java -classpath /opt/cisco/kinetic/efm_server/dslinks/opc/lib/dslink-java-opc-0.2.7-SNAPSHOT.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.external.jcifs_1.2.25.201407161156.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.external.sdk_1.4.0.v20150417-0947.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.external_1.4.0.v20150417-0947.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.jinterop.core_2.1.8.v20140625-1417.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.jinterop.deps_1.3.0.v20141013-0754.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.jinterop.sdk_1.4.0.v20150417-0949.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.jinterop_1.4.0.v20150417-0949.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.opc.dcom_1.2.0.v20141105-1322.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.opc.lib_1.3.0.v20141118-1249.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.opc.xmlda_1.4.0.v20150424-1204.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.utgard.sdk_1.4.0.v20150424-1204.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.utgard_1.4.0.v20150424-1204.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/dslink-0.12.0.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/commons-logging-1.1.1.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/commons-lang3-3.4.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.eclipse.scada.utils-0.3.0-SNAPSHOT.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/runtime_shared-0.12.0.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/logging-0.12.0.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/commons-beanutils-1.9.2.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/jackson-core-2.6.2.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/jcommander-1.48.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/bcprov-jdk15on-1.51.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/netty-all-5.0.0.Alpha2.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/commons-collections-3.2.1.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/slf4j-api-1.7.12.jar opc.Main --name OPC --nodes nodes.json --key .key --log debug --broker http://127.0.0.1:46021/conn --token 3VGXkGJxxDETmp89urlmdZG8IsknRbooTubzyjrc5YwddTsB [2018-06-12 10:01:52.169082] 2018-06-12 10:01:52.167 [Thread-6] DEBUG org.dsa.iot.dslink.connection.ConnectionManager - Initiating connection sequence [2018-06-12 10:01:52.195935] 2018-06-12 10:01:52.195 [Thread-6] DEBUG io.netty.util.internal.logging.InternalLoggerFactory - Using SLF4J as the default logging framework [2018-06-12 10:01:52.205751] 2018-06-12 10:01:52.205 [Thread-6] DEBUG io.netty.channel.MultithreadEventLoopGroup - -Dio.netty.eventLoopThreads: 16 [2018-06-12 10:01:52.312496] 2018-06-12 10:01:52.312 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent0 - java.nio.Buffer.address: available [2018-06-12 10:01:52.312582] 2018-06-12 10:01:52.312 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent0 - sun.misc.Unsafe.theUnsafe: available [2018-06-12 10:01:52.312817] 2018-06-12 10:01:52.312 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent0 - sun.misc.Unsafe.copyMemory: available [2018-06-12 10:01:52.313071] 2018-06-12 10:01:52.312 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent0 - java.nio.Bits.unaligned: true [2018-06-12 10:01:52.313608] 2018-06-12 10:01:52.313 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - Java version: 8 [2018-06-12 10:01:52.313808] 2018-06-12 10:01:52.313 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.noUnsafe: false [2018-06-12 10:01:52.313970] 2018-06-12 10:01:52.313 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - sun.misc.Unsafe: available [2018-06-12 10:01:52.314296] 2018-06-12 10:01:52.314 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.noJavassist: false [2018-06-12 10:01:52.317132] 2018-06-12 10:01:52.316 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - Javassist: unavailable [2018-06-12 10:01:52.317260] 2018-06-12 10:01:52.317 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - 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. [2018-06-12 10:01:52.317523] 2018-06-12 10:01:52.317 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.tmpdir: /tmp (java.io.tmpdir) [2018-06-12 10:01:52.317940] 2018-06-12 10:01:52.317 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.bitMode: 64 (sun.arch.data.model) [2018-06-12 10:01:52.318062] 2018-06-12 10:01:52.317 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.noPreferDirect: false [2018-06-12 10:01:52.318533] 2018-06-12 10:01:52.318 [Thread-6] DEBUG io.netty.channel.nio.NioEventLoop - -Dio.netty.noKeySetOptimization: false [2018-06-12 10:01:52.318680] 2018-06-12 10:01:52.318 [Thread-6] DEBUG io.netty.channel.nio.NioEventLoop - -Dio.netty.selectorAutoRebuildThreshold: 512 [2018-06-12 10:01:52.394683] 2018-06-12 10:01:52.394 [Thread-6] DEBUG io.netty.channel.DefaultChannelId - -Dio.netty.processId: 11177 (auto-detected) [2018-06-12 10:01:52.399911] 2018-06-12 10:01:52.399 [Thread-6] DEBUG io.netty.channel.DefaultChannelId - -Dio.netty.machineId: 00:50:56:ff:fe:aa:07:75 (auto-detected) [2018-06-12 10:01:52.400110] 2018-06-12 10:01:52.399 [Thread-6] DEBUG io.netty.util.internal.ThreadLocalRandom - -Dio.netty.initialSeedUniquifier: 0x99c4f246a41807db (took 129 ms) [2018-06-12 10:01:52.435349] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.numHeapArenas: 5 [2018-06-12 10:01:52.435389] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.numDirectArenas: 0 [2018-06-12 10:01:52.435547] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.pageSize: 8192 [2018-06-12 10:01:52.435658] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.maxOrder: 11 [2018-06-12 10:01:52.435807] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.chunkSize: 16777216 [2018-06-12 10:01:52.435913] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.tinyCacheSize: 512 [2018-06-12 10:01:52.436027] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.smallCacheSize: 256 [2018-06-12 10:01:52.436150] 2018-06-12 10:01:52.436 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.normalCacheSize: 64 [2018-06-12 10:01:52.436283] 2018-06-12 10:01:52.436 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.maxCachedBufferCapacity: 32768 [2018-06-12 10:01:52.436385] 2018-06-12 10:01:52.436 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.cacheTrimInterval: 8192 [2018-06-12 10:01:52.440200] 2018-06-12 10:01:52.440 [Thread-6] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.allocator.type: pooled [2018-06-12 10:01:52.440304] 2018-06-12 10:01:52.440 [Thread-6] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.threadLocalDirectBufferSize: 65536 [2018-06-12 10:01:52.491380] 2018-06-12 10:01:52.491 [nioEventLoopGroup-0-0] DEBUG io.netty.util.ResourceLeakDetector - -Dio.netty.leakDetectionLevel: simple [2018-06-12 10:01:52.528315] 2018-06-12 10:01:52.528 [Thread-6] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.maxCapacity: 262144 [2018-06-12 10:01:52.549740] 2018-06-12 10:01:52.549 [nioEventLoopGroup-0-0] DEBUG io.netty.util.internal.Cleaner0 - java.nio.ByteBuffer.cleaner(): available [2018-06-12 10:01:52.569104] 2018-06-12 10:01:52.568 [Thread-8] DEBUG org.dsa.iot.dslink.serializer.SerializationManager - Failed to delete original file [2018-06-12 10:01:52.601287] 2018-06-12 10:01:52.601 [nioEventLoopGroup-0-1] DEBUG io.netty.handler.codec.http.websocketx.WebSocketClientHandshaker13 - WebSocket version 13 client handshake key: ks70tDhDYbzsaXrk3vdaqQ==, expected response: lVulOKtZ5I4E915olFwL1qLjwbE= [2018-06-12 10:01:52.615813] 2018-06-12 10:01:52.615 [nioEventLoopGroup-0-1] ERROR org.dsa.iot.dslink.connection.connector.WebSocketConnector - [2018-06-12 10:01:52.615831] io.netty.handler.codec.CodecException: invalid WebSocket Extension handhshake for "permessage-deflate; client_max_window_bits=15" [2018-06-12 10:01:52.615836] at io.netty.handler.codec.http.websocketx.extensions.WebSocketClientExtensionHandler.channelRead(WebSocketClientExtensionHandler.java:111) [2018-06-12 10:01:52.615841] at io.netty.channel.ChannelHandlerInvokerUtil.invokeChannelReadNow(ChannelHandlerInvokerUtil.java:84) [2018-06-12 10:01:52.615848] at io.netty.channel.DefaultChannelHandlerInvoker.invokeChannelRead(DefaultChannelHandlerInvoker.java:153) [2018-06-12 10:01:52.615853] at io.netty.channel.PausableChannelEventExecutor.invokeChannelRead(PausableChannelEventExecutor.java:86) [2018-06-12 10:01:52.615857] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:389) [2018-06-12 10:01:52.615863] at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) [2018-06-12 10:01:52.615868] at io.netty.channel.ChannelHandlerInvokerUtil.invokeChannelReadNow(ChannelHandlerInvokerUtil.java:84) [2018-06-12 10:01:52.615873] at io.netty.channel.DefaultChannelHandlerInvoker.invokeChannelRead(DefaultChannelHandlerInvoker.java:153) [2018-06-12 10:01:52.615877] at io.netty.channel.PausableChannelEventExecutor.invokeChannelRead(PausableChannelEventExecutor.java:86) [2018-06-12 10:01:52.615883] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:389) [2018-06-12 10:01:52.615888] at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:243) [2018-06-12 10:01:52.615892] at io.netty.channel.ChannelHandlerInvokerUtil.invokeChannelReadNow(ChannelHandlerInvokerUtil.java:84) [2018-06-12 10:01:52.615896] at io.netty.channel.DefaultChannelHandlerInvoker.invokeChannelRead(DefaultChannelHandlerInvoker.java:153) [2018-06-12 10:01:52.615903] at io.netty.channel.PausableChannelEventExecutor.invokeChannelRead(PausableChannelEventExecutor.java:86) [2018-06-12 10:01:52.615908] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:389) [2018-06-12 10:01:52.615912] at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:956) [2018-06-12 10:01:52.615916] at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:127) [2018-06-12 10:01:52.615923] at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:514) [2018-06-12 10:01:52.615927] at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:471) [2018-06-12 10:01:52.615932] at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:385) [2018-06-12 10:01:52.615936] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:351) [2018-06-12 10:01:52.615941] at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:116) [2018-06-12 10:01:52.615946] at io.netty.util.internal.chmv8.ForkJoinTask$RunnableExecuteAction.exec(ForkJoinTask.java:1412) [2018-06-12 10:01:52.615950] at io.netty.util.internal.chmv8.ForkJoinTask.doExec(ForkJoinTask.java:280) [2018-06-12 10:01:52.615954] at io.netty.util.internal.chmv8.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:877) [2018-06-12 10:01:52.615959] at io.netty.util.internal.chmv8.ForkJoinPool.scan(ForkJoinPool.java:1706) [2018-06-12 10:01:52.615965] at io.netty.util.internal.chmv8.ForkJoinPool.runWorker(ForkJoinPool.java:1661) [2018-06-12 10:01:52.615969] at io.netty.util.internal.chmv8.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:126) [2018-06-12 10:01:52.615973] [2018-06-12 10:01:52.620112] Exception in thread "Thread-6" io.netty.handler.codec.CodecException: invalid WebSocket Extension handhshake for "permessage-deflate; client_max_window_bits=15" [2018-06-12 10:01:52.620155] at io.netty.handler.codec.http.websocketx.extensions.WebSocketClientExtensionHandler.channelRead(WebSocketClientExtensionHandler.java:111) [2018-06-12 10:01:52.620163] at io.netty.channel.ChannelHandlerInvokerUtil.invokeChannelReadNow(ChannelHandlerInvokerUtil.java:84) [2018-06-12 10:01:52.620193] at io.netty.channel.DefaultChannelHandlerInvoker.invokeChannelRead(DefaultChannelHandlerInvoker.java:153) [2018-06-12 10:01:52.620220] at io.netty.channel.PausableChannelEventExecutor.invokeChannelRead(PausableChannelEventExecutor.java:86) [2018-06-12 10:01:52.620243] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:389) [2018-06-12 10:01:52.620265] at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) [2018-06-12 10:01:52.620320] at io.netty.channel.ChannelHandlerInvokerUtil.invokeChannelReadNow(ChannelHandlerInvokerUtil.java:84) [2018-06-12 10:01:52.620346] at io.netty.channel.DefaultChannelHandlerInvoker.invokeChannelRead(DefaultChannelHandlerInvoker.java:153) [2018-06-12 10:01:52.620369] at io.netty.channel.PausableChannelEventExecutor.invokeChannelRead(PausableChannelEventExecutor.java:86) [2018-06-12 10:01:52.620410] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:389) [2018-06-12 10:01:52.620446] at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:243) [2018-06-12 10:01:52.620507] at io.netty.channel.ChannelHandlerInvokerUtil.invokeChannelReadNow(ChannelHandlerInvokerUtil.java:84) [2018-06-12 10:01:52.620530] at io.netty.channel.DefaultChannelHandlerInvoker.invokeChannelRead(DefaultChannelHandlerInvoker.java:153) [2018-06-12 10:01:52.620567] at io.netty.channel.PausableChannelEventExecutor.invokeChannelRead(PausableChannelEventExecutor.java:86) [2018-06-12 10:01:52.620613] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:389) [2018-06-12 10:01:52.620641] at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:956) [2018-06-12 10:01:52.620670] at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:127) [2018-06-12 10:01:52.620694] at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:514) [2018-06-12 10:01:52.620734] at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:471) [2018-06-12 10:01:52.620768] at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:385) [2018-06-12 10:01:52.620807] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:351)
2018-06-12 10:01:51.340431] == Executing bash with arguments [/opt/cisco/kinetic/efm_server/dslinks/opc/bin/dslink-java-opc, --name, OPC, --nodes, nodes.json, --key, .key, --log, debug, --broker, http://127.0.0.1:46021/conn, --token, 3VGXkGJxxDETmp89urlmdZG8IsknRbooTubzyjrc5YwddTsB] (pid: 11177) == [2018-06-12 10:01:51.354732] java -classpath /opt/cisco/kinetic/efm_server/dslinks/opc/lib/dslink-java-opc-0.2.7-SNAPSHOT.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.external.jcifs_1.2.25.201407161156.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.external.sdk_1.4.0.v20150417-0947.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.external_1.4.0.v20150417-0947.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.jinterop.core_2.1.8.v20140625-1417.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.jinterop.deps_1.3.0.v20141013-0754.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.jinterop.sdk_1.4.0.v20150417-0949.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.jinterop_1.4.0.v20150417-0949.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.opc.dcom_1.2.0.v20141105-1322.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.opc.lib_1.3.0.v20141118-1249.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.opc.xmlda_1.4.0.v20150424-1204.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.utgard.sdk_1.4.0.v20150424-1204.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.openscada.utgard_1.4.0.v20150424-1204.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/dslink-0.12.0.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/commons-logging-1.1.1.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/commons-lang3-3.4.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/org.eclipse.scada.utils-0.3.0-SNAPSHOT.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/runtime_shared-0.12.0.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/logging-0.12.0.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/commons-beanutils-1.9.2.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/jackson-core-2.6.2.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/jcommander-1.48.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/bcprov-jdk15on-1.51.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/netty-all-5.0.0.Alpha2.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/commons-collections-3.2.1.jar:/opt/cisco/kinetic/efm_server/dslinks/opc/lib/slf4j-api-1.7.12.jar opc.Main --name OPC --nodes nodes.json --key .key --log debug --broker http://127.0.0.1:46021/conn --token 3VGXkGJxxDETmp89urlmdZG8IsknRbooTubzyjrc5YwddTsB [2018-06-12 10:01:52.169082] 2018-06-12 10:01:52.167 [Thread-6] DEBUG org.dsa.iot.dslink.connection.ConnectionManager - Initiating connection sequence [2018-06-12 10:01:52.195935] 2018-06-12 10:01:52.195 [Thread-6] DEBUG io.netty.util.internal.logging.InternalLoggerFactory - Using SLF4J as the default logging framework [2018-06-12 10:01:52.205751] 2018-06-12 10:01:52.205 [Thread-6] DEBUG io.netty.channel.MultithreadEventLoopGroup - -Dio.netty.eventLoopThreads: 16 [2018-06-12 10:01:52.312496] 2018-06-12 10:01:52.312 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent0 - java.nio.Buffer.address: available [2018-06-12 10:01:52.312582] 2018-06-12 10:01:52.312 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent0 - sun.misc.Unsafe.theUnsafe: available [2018-06-12 10:01:52.312817] 2018-06-12 10:01:52.312 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent0 - sun.misc.Unsafe.copyMemory: available [2018-06-12 10:01:52.313071] 2018-06-12 10:01:52.312 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent0 - java.nio.Bits.unaligned: true [2018-06-12 10:01:52.313608] 2018-06-12 10:01:52.313 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - Java version: 8 [2018-06-12 10:01:52.313808] 2018-06-12 10:01:52.313 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.noUnsafe: false [2018-06-12 10:01:52.313970] 2018-06-12 10:01:52.313 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - sun.misc.Unsafe: available [2018-06-12 10:01:52.314296] 2018-06-12 10:01:52.314 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.noJavassist: false [2018-06-12 10:01:52.317132] 2018-06-12 10:01:52.316 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - Javassist: unavailable [2018-06-12 10:01:52.317260] 2018-06-12 10:01:52.317 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - 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. [2018-06-12 10:01:52.317523] 2018-06-12 10:01:52.317 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.tmpdir: /tmp (java.io.tmpdir) [2018-06-12 10:01:52.317940] 2018-06-12 10:01:52.317 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.bitMode: 64 (sun.arch.data.model) [2018-06-12 10:01:52.318062] 2018-06-12 10:01:52.317 [Thread-6] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.noPreferDirect: false [2018-06-12 10:01:52.318533] 2018-06-12 10:01:52.318 [Thread-6] DEBUG io.netty.channel.nio.NioEventLoop - -Dio.netty.noKeySetOptimization: false [2018-06-12 10:01:52.318680] 2018-06-12 10:01:52.318 [Thread-6] DEBUG io.netty.channel.nio.NioEventLoop - -Dio.netty.selectorAutoRebuildThreshold: 512 [2018-06-12 10:01:52.394683] 2018-06-12 10:01:52.394 [Thread-6] DEBUG io.netty.channel.DefaultChannelId - -Dio.netty.processId: 11177 (auto-detected) [2018-06-12 10:01:52.399911] 2018-06-12 10:01:52.399 [Thread-6] DEBUG io.netty.channel.DefaultChannelId - -Dio.netty.machineId: 00:50:56:ff:fe:aa:07:75 (auto-detected) [2018-06-12 10:01:52.400110] 2018-06-12 10:01:52.399 [Thread-6] DEBUG io.netty.util.internal.ThreadLocalRandom - -Dio.netty.initialSeedUniquifier: 0x99c4f246a41807db (took 129 ms) [2018-06-12 10:01:52.435349] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.numHeapArenas: 5 [2018-06-12 10:01:52.435389] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.numDirectArenas: 0 [2018-06-12 10:01:52.435547] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.pageSize: 8192 [2018-06-12 10:01:52.435658] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.maxOrder: 11 [2018-06-12 10:01:52.435807] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.chunkSize: 16777216 [2018-06-12 10:01:52.435913] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.tinyCacheSize: 512 [2018-06-12 10:01:52.436027] 2018-06-12 10:01:52.435 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.smallCacheSize: 256 [2018-06-12 10:01:52.436150] 2018-06-12 10:01:52.436 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.normalCacheSize: 64 [2018-06-12 10:01:52.436283] 2018-06-12 10:01:52.436 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.maxCachedBufferCapacity: 32768 [2018-06-12 10:01:52.436385] 2018-06-12 10:01:52.436 [Thread-6] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.cacheTrimInterval: 8192 [2018-06-12 10:01:52.440200] 2018-06-12 10:01:52.440 [Thread-6] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.allocator.type: pooled [2018-06-12 10:01:52.440304] 2018-06-12 10:01:52.440 [Thread-6] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.threadLocalDirectBufferSize: 65536 [2018-06-12 10:01:52.491380] 2018-06-12 10:01:52.491 [nioEventLoopGroup-0-0] DEBUG io.netty.util.ResourceLeakDetector - -Dio.netty.leakDetectionLevel: simple [2018-06-12 10:01:52.528315] 2018-06-12 10:01:52.528 [Thread-6] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.maxCapacity: 262144 [2018-06-12 10:01:52.549740] 2018-06-12 10:01:52.549 [nioEventLoopGroup-0-0] DEBUG io.netty.util.internal.Cleaner0 - java.nio.ByteBuffer.cleaner(): available [2018-06-12 10:01:52.569104] 2018-06-12 10:01:52.568 [Thread-8] DEBUG org.dsa.iot.dslink.serializer.SerializationManager - Failed to delete original file [2018-06-12 10:01:52.601287] 2018-06-12 10:01:52.601 [nioEventLoopGroup-0-1] DEBUG io.netty.handler.codec.http.websocketx.WebSocketClientHandshaker13 - WebSocket version 13 client handshake key: ks70tDhDYbzsaXrk3vdaqQ==, expected response: lVulOKtZ5I4E915olFwL1qLjwbE= [2018-06-12 10:01:52.615813] 2018-06-12 10:01:52.615 [nioEventLoopGroup-0-1] ERROR org.dsa.iot.dslink.connection.connector.WebSocketConnector - [2018-06-12 10:01:52.615831] io.netty.handler.codec.CodecException: invalid WebSocket Extension handhshake for "permessage-deflate; client_max_window_bits=15" [2018-06-12 10:01:52.615836] at io.netty.handler.codec.http.websocketx.extensions.WebSocketClientExtensionHandler.channelRead(WebSocketClientExtensionHandler.java:111) [2018-06-12 10:01:52.615841] at io.netty.channel.ChannelHandlerInvokerUtil.invokeChannelReadNow(ChannelHandlerInvokerUtil.java:84) [2018-06-12 10:01:52.615848] at io.netty.channel.DefaultChannelHandlerInvoker.invokeChannelRead(DefaultChannelHandlerInvoker.java:153) [2018-06-12 10:01:52.615853] at io.netty.channel.PausableChannelEventExecutor.invokeChannelRead(PausableChannelEventExecutor.java:86) [2018-06-12 10:01:52.615857] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:389) [2018-06-12 10:01:52.615863] at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) [2018-06-12 10:01:52.615868] at io.netty.channel.ChannelHandlerInvokerUtil.invokeChannelReadNow(ChannelHandlerInvokerUtil.java:84) [2018-06-12 10:01:52.615873] at io.netty.channel.DefaultChannelHandlerInvoker.invokeChannelRead(DefaultChannelHandlerInvoker.java:153) [2018-06-12 10:01:52.615877] at io.netty.channel.PausableChannelEventExecutor.invokeChannelRead(PausableChannelEventExecutor.java:86) [2018-06-12 10:01:52.615883] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:389) [2018-06-12 10:01:52.615888] at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:243) [2018-06-12 10:01:52.615892] at io.netty.channel.ChannelHandlerInvokerUtil.invokeChannelReadNow(ChannelHandlerInvokerUtil.java:84) [2018-06-12 10:01:52.615896] at io.netty.channel.DefaultChannelHandlerInvoker.invokeChannelRead(DefaultChannelHandlerInvoker.java:153) [2018-06-12 10:01:52.615903] at io.netty.channel.PausableChannelEventExecutor.invokeChannelRead(PausableChannelEventExecutor.java:86) [2018-06-12 10:01:52.615908] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:389) [2018-06-12 10:01:52.615912] at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:956) [2018-06-12 10:01:52.615916] at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:127) [2018-06-12 10:01:52.615923] at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:514) [2018-06-12 10:01:52.615927] at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:471) [2018-06-12 10:01:52.615932] at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:385) [2018-06-12 10:01:52.615936] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:351) [2018-06-12 10:01:52.615941] at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:116) [2018-06-12 10:01:52.615946] at io.netty.util.internal.chmv8.ForkJoinTask$RunnableExecuteAction.exec(ForkJoinTask.java:1412) [2018-06-12 10:01:52.615950] at io.netty.util.internal.chmv8.ForkJoinTask.doExec(ForkJoinTask.java:280) [2018-06-12 10:01:52.615954] at io.netty.util.internal.chmv8.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:877) [2018-06-12 10:01:52.615959] at io.netty.util.internal.chmv8.ForkJoinPool.scan(ForkJoinPool.java:1706) [2018-06-12 10:01:52.615965] at io.netty.util.internal.chmv8.ForkJoinPool.runWorker(ForkJoinPool.java:1661) [2018-06-12 10:01:52.615969] at io.netty.util.internal.chmv8.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:126) [2018-06-12 10:01:52.615973] [2018-06-12 10:01:52.620112] Exception in thread "Thread-6" io.netty.handler.codec.CodecException: invalid WebSocket Extension handhshake for "permessage-deflate; client_max_window_bits=15" [2018-06-12 10:01:52.620155] at io.netty.handler.codec.http.websocketx.extensions.WebSocketClientExtensionHandler.channelRead(WebSocketClientExtensionHandler.java:111) [2018-06-12 10:01:52.620163] at io.netty.channel.ChannelHandlerInvokerUtil.invokeChannelReadNow(ChannelHandlerInvokerUtil.java:84) [2018-06-12 10:01:52.620193] at io.netty.channel.DefaultChannelHandlerInvoker.invokeChannelRead(DefaultChannelHandlerInvoker.java:153) [2018-06-12 10:01:52.620220] at io.netty.channel.PausableChannelEventExecutor.invokeChannelRead(PausableChannelEventExecutor.java:86) [2018-06-12 10:01:52.620243] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:389) [2018-06-12 10:01:52.620265] at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) [2018-06-12 10:01:52.620320] at io.netty.channel.ChannelHandlerInvokerUtil.invokeChannelReadNow(ChannelHandlerInvokerUtil.java:84) [2018-06-12 10:01:52.620346] at io.netty.channel.DefaultChannelHandlerInvoker.invokeChannelRead(DefaultChannelHandlerInvoker.java:153) [2018-06-12 10:01:52.620369] at io.netty.channel.PausableChannelEventExecutor.invokeChannelRead(PausableChannelEventExecutor.java:86) [2018-06-12 10:01:52.620410] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:389) [2018-06-12 10:01:52.620446] at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:243) [2018-06-12 10:01:52.620507] at io.netty.channel.ChannelHandlerInvokerUtil.invokeChannelReadNow(ChannelHandlerInvokerUtil.java:84) [2018-06-12 10:01:52.620530] at io.netty.channel.DefaultChannelHandlerInvoker.invokeChannelRead(DefaultChannelHandlerInvoker.java:153) [2018-06-12 10:01:52.620567] at io.netty.channel.PausableChannelEventExecutor.invokeChannelRead(PausableChannelEventExecutor.java:86) [2018-06-12 10:01:52.620613] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:389) [2018-06-12 10:01:52.620641] at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:956) [2018-06-12 10:01:52.620670] at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:127) [2018-06-12 10:01:52.620694] at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:514) [2018-06-12 10:01:52.620734] at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:471) [2018-06-12 10:01:52.620768] at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:385) [2018-06-12 10:01:52.620807] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:351)