Anisotrop
commented
7 years ago Example before:
/usr/lib/jvm/java-8-openjdk-amd64/bin/java -ea -javaagent:/home/osboxes/SystemProjects/btrace/build/btrace-agent.jar=script=/home/osboxes/SystemProjects/btrace/com/sun/btrace/samples/AllCalls.class -Didea.test.cyclic.buffer.size=41943040 -javaagent:/opt/intellij-idea-community.2017.1.1/lib/idea_rt.jar=38204:/opt/intellij-idea-community.2017.1.1/bin -Dfile.encoding=UTF-8 -classpath /opt/intellij-idea-community.2017.1.1/lib/idea_rt.jar:/opt/intellij-idea-community.2017.1.1/plugins/junit/lib/junit-rt.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/charsets.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext/cldrdata.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext/dnsns.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext/icedtea-sound.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext/jaccess.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext/localedata.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext/nashorn.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext/sunec.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext/sunjce_provider.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext/sunpkcs11.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext/zipfs.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/jce.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/jsse.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/management-agent.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/resources.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/rt.jar:/usr/lib/jvm/java-8-openjdk-amd64/lib/tools.jar:/home/osboxes/IdeaProjects/enterprise.gateway/transport/socks/target/test-classes:/home/osboxes/IdeaProjects/enterprise.gateway/transport/socks/target/classes:/home/osboxes/IdeaProjects/enterprise.gateway/resource.address/socks/target/classes:/home/osboxes/.m2/repository/org/kaazing/gateway.resource.address/develop-SNAPSHOT/gateway.resource.address-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/slf4j/slf4j-api/1.7.21/slf4j-api-1.7.21.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.util/develop-SNAPSHOT/gateway.util-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.transport/develop-SNAPSHOT/gateway.transport-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.security/develop-SNAPSHOT/gateway.security-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/mina.netty/develop-SNAPSHOT/mina.netty-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/agrona/Agrona/0.9.1/Agrona-0.9.1.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.server/develop-SNAPSHOT/gateway.server-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.service/develop-SNAPSHOT/gateway.service-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/com/hazelcast/hazelcast/3.7.4/hazelcast-3.7.4.jar:/home/osboxes/.m2/repository/com/hazelcast/hazelcast-client/3.7.4/hazelcast-client-3.7.4.jar:/home/osboxes/.m2/repository/com/hazelcast/hazelcast-aws/1.1.1/hazelcast-aws-1.1.1.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.resource.address.http/develop-SNAPSHOT/gateway.resource.address.http-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.server.api/develop-SNAPSHOT/gateway.server.api-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.server.spi/develop-SNAPSHOT/gateway.server.spi-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/apache/xmlbeans/xmlbeans/2.4.0/xmlbeans-2.4.0.jar:/home/osboxes/.m2/repository/stax/stax-api/1.0.1/stax-api-1.0.1.jar:/home/osboxes/.m2/repository/org/jdom/jdom/1.1/jdom-1.1.jar:/home/osboxes/.m2/repository/commons-cli/commons-cli/1.2/commons-cli-1.2.jar:/home/osboxes/.m2/repository/org/slf4j/slf4j-log4j12/1.7.21/slf4j-log4j12-1.7.21.jar:/home/osboxes/.m2/repository/log4j/log4j/1.2.17/log4j-1.2.17.jar:/home/osboxes/.m2/repository/net/sf/opencsv/opencsv/2.3/opencsv-2.3.jar:/home/osboxes/.m2/repository/org/json/json/20090211/json-20090211.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.resource.address.pipe/develop-SNAPSHOT/gateway.resource.address.pipe-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.resource.address.httpxe/develop-SNAPSHOT/gateway.resource.address.httpxe-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.transport.pipe/develop-SNAPSHOT/gateway.transport.pipe-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.transport.bio/develop-SNAPSHOT/gateway.transport.bio-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.resource.address.udp/develop-SNAPSHOT/gateway.resource.address.udp-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.service.echo/develop-SNAPSHOT/gateway.service.echo-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.service.proxy/develop-SNAPSHOT/gateway.service.proxy-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.transport.nio/develop-SNAPSHOT/gateway.transport.nio-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.resource.address.tcp/develop-SNAPSHOT/gateway.resource.address.tcp-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/test.util/develop-SNAPSHOT/test.util-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.transport.wsn/develop-SNAPSHOT/gateway.transport.wsn-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.resource.address.wsn/develop-SNAPSHOT/gateway.resource.address.wsn-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.resource.address.ws/develop-SNAPSHOT/gateway.resource.address.ws-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.transport.ws/develop-SNAPSHOT/gateway.transport.ws-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.transport.wseb/develop-SNAPSHOT/gateway.transport.wseb-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.resource.address.wse/develop-SNAPSHOT/gateway.resource.address.wse-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.transport.http/develop-SNAPSHOT/gateway.transport.http-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.transport.ssl/develop-SNAPSHOT/gateway.transport.ssl-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/gateway.resource.address.ssl/develop-SNAPSHOT/gateway.resource.address.ssl-develop-SNAPSHOT.jar:/home/osboxes/.m2/repository/org/kaazing/k3po.junit/3.0.0-alpha-65/k3po.junit-3.0.0-alpha-65.jar:/home/osboxes/.m2/repository/org/kaazing/net.tcp/1.1.0.9/net.tcp-1.1.0.9.jar:/home/osboxes/.m2/repository/org/kaazing/net.api/1.1.0.9/net.api-1.1.0.9.jar:/home/osboxes/.m2/repository/org/kaazing/community.license/2.18/community.license-2.18.jar:/home/osboxes/.m2/repository/junit/junit/4.12/junit-4.12.jar:/home/osboxes/.m2/repository/org/hamcrest/hamcrest-core/1.3/hamcrest-core-1.3.jar:/home/osboxes/.m2/repository/org/jmock/jmock/2.6.0/jmock-2.6.0.jar:/home/osboxes/.m2/repository/org/hamcrest/hamcrest-library/1.3/hamcrest-library-1.3.jar:/home/osboxes/.m2/repository/org/jmock/jmock-legacy/2.6.0/jmock-legacy-2.6.0.jar:/home/osboxes/.m2/repository/org/objenesis/objenesis/1.0/objenesis-1.0.jar:/home/osboxes/.m2/repository/cglib/cglib-nodep/2.1_3/cglib-nodep-2.1_3.jar:/home/osboxes/.m2/repository/com/kaazing/gateway/com.kaazing.gateway.truststore.tests/1.0.0.16/com.kaazing.gateway.truststore.tests-1.0.0.16-keystore.jar:/home/osboxes/.m2/repository/com/kaazing/gateway/com.kaazing.gateway.truststore/1.0.0.54/com.kaazing.gateway.truststore-1.0.0.54.jar:/home/osboxes/.m2/repository/com/kaazing/specification.sock5plus/1.0.1/specification.sock5plus-1.0.1.jar:/home/osboxes/.m2/repository/org/kaazing/k3po.lang/5.0.6/k3po.lang-5.0.6.jar:/home/osboxes/.m2/repository/org/antlr/antlr4-runtime/4.2.2/antlr4-runtime-4.2.2.jar:/home/osboxes/.m2/repository/org/abego/treelayout/org.abego.treelayout.core/1.0.1/org.abego.treelayout.core-1.0.1.jar:/home/osboxes/.m2/repository/org/antlr/antlr4-annotations/4.2.2/antlr4-annotations-4.2.2.jar:/home/osboxes/.m2/repository/de/odysseus/juel/juel-api/2.2.7/juel-api-2.2.7.jar:/home/osboxes/.m2/repository/de/odysseus/juel/juel-impl/2.2.7/juel-impl-2.2.7.jar:/home/osboxes/.m2/repository/commons-codec/commons-codec/1.10/commons-codec-1.10.jar:/home/osboxes/.m2/repository/org/kaazing/specification.tcp/3.0.0-alpha-65/specification.tcp-3.0.0-alpha-65.jar:/home/osboxes/.m2/repository/org/kaazing/specification.ws/3.0.0-alpha-65/specification.ws-3.0.0-alpha-65.jar com.intellij.rt.execution.junit.JUnitStarter -ideVersion5 com.kaazing.gateway.transport.socks.WsnOverSocksForwardIT,clientSentData
btrace INFO: Redirecting output to /home/osboxes/SystemProjects/btrace/com/sun/btrace/samples/AllCalls.class-.default.1495013752031.btraceclientSentData(com.kaazing.gateway.transport.socks.WsnOverSocksForwardIT) starting
WsnOverSocksForwardIT.clientSentData FAILED with exception java.lang.Exception: Triggered failure (42.53 secs)
java.lang.Exception: Triggered failure
at com.kaazing.gateway.transport.socks.WsnOverSocksForwardIT.clientSentData(WsnOverSocksForwardIT.java:95)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
at org.kaazing.gateway.server.test.GatewayRule$GatewayStatement.evaluate(GatewayRule.java:74)
at org.kaazing.gateway.server.test.GatewayRule$GatewayStatement.evaluate(GatewayRule.java:74)
at org.kaazing.k3po.junit.rules.SpecificationStatement.evaluate(SpecificationStatement.java:70)
at org.junit.rules.Verifier$1.evaluate(Verifier.java:35)
at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:298)
at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:292)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.lang.Thread.run(Thread.java:745)
=================== BEGIN STORED LOG MESSAGES ===========================
Printing last 199 of 199 log messages
0 org.kaazing.gateway.server.context.resolve.DefaultSecurityContext [Time-limited test] WARN DefaultSecurityContext - Keystore is not specified
1769 org.kaazing.gateway.server.context.resolve.DefaultAcceptOptionsContext [Time-limited test] TRACE DefaultAcceptOptionsContext - Accept options map = {supportedProtocols=[Ljava.lang.String;@195339d3, ws.inactivityTimeout=0, ws[ws/rfc6455].ws[ws/rfc6455].inactivityTimeout=0, ws[ws/draft-7x].ws[ws/draft-7x].inactivityTimeout=0, ws.maxMessageSize=131072, ws[ws/rfc6455].ws[ws/rfc6455].maxMessageSize=131072, ws[ws/draft-7x].ws[ws/draft-7x].maxMessageSize=131072, http[http/1.1].keepAliveTimeout=30, http[x-kaazing-handshake].keepAliveTimeout=30, http[httpxe/1.1].keepAliveTimeout=30, http[httpxe/1.1].http[http/1.1].keepAliveTimeout=30, ssl.encryptionEnabled=true, socks.ssl.wantClientAuth=false, socks.ssl.needClientAuth=false, http.serverHeaderEnabled=true, ssl.wantClientAuth=false, ssl.needClientAuth=false, tcp.maximumOutboundRate=4294967295}
1935 org.kaazing.gateway.service.proxy.AbstractProxyHandler [Time-limited test] DEBUG AbstractProxyHandler - Proxy handler org.kaazing.gateway.service.proxy.ProxyServiceHandler@39e9123d: maximum.pending.bytes=64000, using resume threshold 32000
1935 org.kaazing.gateway.service.proxy.AbstractProxyHandler [Time-limited test] DEBUG AbstractProxyHandler - Proxy handler org.kaazing.gateway.service.proxy.ProxyServiceHandler$ConnectHandler@3882f3de: maximum.pending.bytes=64000, using resume threshold 32000
1936 org.kaazing.gateway.service.proxy.AbstractProxyHandler [Time-limited test] DEBUG AbstractProxyHandler - Proxy handler org.kaazing.gateway.service.proxy.ProxyServiceHandler@39e9123d: maximum.transferred.bytes=-1.
1936 org.kaazing.gateway.service.proxy.AbstractProxyHandler [Time-limited test] DEBUG AbstractProxyHandler - Proxy handler org.kaazing.gateway.service.proxy.ProxyServiceHandler@39e9123d: maximum.recovery.interval=0.
1945 org.kaazing.gateway.service.proxy.AbstractProxyHandler [Time-limited test] DEBUG AbstractProxyHandler - Proxy handler org.kaazing.gateway.service.proxy.ProxyServiceHandler@39e9123d: connect.strategy=IMMEDIATE.
1949 service.proxy [Time-limited test] DEBUG proxy - proxy service with thread alignment, using prepared.connection.count=0
1953 org.kaazing.gateway.server.context.resolve.DefaultAcceptOptionsContext [Time-limited test] TRACE DefaultAcceptOptionsContext - Accept options map = {supportedProtocols=[Ljava.lang.String;@768b6616, ws.inactivityTimeout=0, ws[ws/rfc6455].ws[ws/rfc6455].inactivityTimeout=0, ws[ws/draft-7x].ws[ws/draft-7x].inactivityTimeout=0, ws.maxMessageSize=131072, ws[ws/rfc6455].ws[ws/rfc6455].maxMessageSize=131072, ws[ws/draft-7x].ws[ws/draft-7x].maxMessageSize=131072, http[http/1.1].keepAliveTimeout=30, http[x-kaazing-handshake].keepAliveTimeout=30, http[httpxe/1.1].keepAliveTimeout=30, http[httpxe/1.1].http[http/1.1].keepAliveTimeout=30, ssl.encryptionEnabled=true, socks.ssl.wantClientAuth=false, socks.ssl.needClientAuth=false, http.serverHeaderEnabled=true, ssl.wantClientAuth=false, ssl.needClientAuth=false, tcp.maximumOutboundRate=4294967295}
2136 transport.tcp.accept [Time-limited test] DEBUG accept - TCP acceptor: worker count = 2
2157 org.jboss.netty.channel.socket.nio.SelectorUtil [Time-limited test] DEBUG SelectorUtil - Using select timeout of 10
2157 org.jboss.netty.channel.socket.nio.SelectorUtil [Time-limited test] DEBUG SelectorUtil - Epoll-bug workaround enabled = false
2379 org.kaazing.gateway.transport.nio.internal.AbstractNioAcceptor [Time-limited test] INFO AbstractNioAcceptor - Bound to resource: tcp://127.0.0.1:8080
2384 ha [Time-limited test] DEBUG ha - Exit Bind
2395 org.kaazing.gateway.server.context.resolve.DefaultConnectOptionsContext [Time-limited test] TRACE DefaultConnectOptionsContext - Connect options map = {protocolVersion=RFC_6455, ws.inactivityTimeout=60000, http[http/1.1].keepAliveTimeout=60, http[http/1.1].keepAlive=true, ssl.encryptionEnabled=true, ssl.wantClientAuth=false, ssl.needClientAuth=false, socks.ssl.wantClientAuth=false, socks.ssl.needClientAuth=false, http[http/1.1].transport=socks://localhost:1080, socks.timeout=5 seconds}
2952 org.kaazing.gateway.server.Gateway [Time-limited test] INFO Gateway - Starting server
2952 org.kaazing.gateway.server.Gateway [Time-limited test] INFO Gateway - Starting services
2952 org.kaazing.gateway.server.Gateway [Time-limited test] INFO Gateway - tcp://localhost:8080
2952 org.kaazing.gateway.server.Gateway [Time-limited test] INFO Gateway - Started services
2957 org.kaazing.gateway.server.Gateway [Time-limited test] INFO Gateway - Started server successfully in 1.029 secs at 2017-05-17 12:35:59
2957 ha [Time-limited test] DEBUG ha - Exit Gateway launcher initInternal
3013 org.kaazing.gateway.server.context.resolve.DefaultSecurityContext [Time-limited test] WARN DefaultSecurityContext - Keystore is not specified
3205 org.kaazing.gateway.server.context.resolve.DefaultAcceptOptionsContext [Time-limited test] TRACE DefaultAcceptOptionsContext - Accept options map = {supportedProtocols=[Ljava.lang.String;@720b588, ws.inactivityTimeout=60000, ws[ws/rfc6455].ws[ws/rfc6455].inactivityTimeout=60000, ws[ws/draft-7x].ws[ws/draft-7x].inactivityTimeout=60000, ws.maxMessageSize=131072, ws[ws/rfc6455].ws[ws/rfc6455].maxMessageSize=131072, ws[ws/draft-7x].ws[ws/draft-7x].maxMessageSize=131072, http[http/1.1].keepAliveTimeout=60, http[x-kaazing-handshake].keepAliveTimeout=60, http[httpxe/1.1].keepAliveTimeout=60, http[httpxe/1.1].http[http/1.1].keepAliveTimeout=60, ssl.encryptionEnabled=true, socks.ssl.wantClientAuth=false, socks.ssl.needClientAuth=false, http.serverHeaderEnabled=true, ssl.wantClientAuth=false, ssl.needClientAuth=false, http[http/1.1].transport=socks://localhost:1080, tcp.maximumOutboundRate=4294967295, socks.retry.maximum.interval=1 second}
3288 org.kaazing.gateway.service.proxy.AbstractProxyHandler [Time-limited test] DEBUG AbstractProxyHandler - Proxy handler org.kaazing.gateway.service.proxy.ProxyServiceHandler@12bac8c: maximum.pending.bytes=64000, using resume threshold 32000
3289 org.kaazing.gateway.service.proxy.AbstractProxyHandler [Time-limited test] DEBUG AbstractProxyHandler - Proxy handler org.kaazing.gateway.service.proxy.ProxyServiceHandler$ConnectHandler@5cd0354b: maximum.pending.bytes=64000, using resume threshold 32000
3290 org.kaazing.gateway.service.proxy.AbstractProxyHandler [Time-limited test] DEBUG AbstractProxyHandler - Proxy handler org.kaazing.gateway.service.proxy.ProxyServiceHandler@12bac8c: maximum.transferred.bytes=-1.
3290 org.kaazing.gateway.service.proxy.AbstractProxyHandler [Time-limited test] DEBUG AbstractProxyHandler - Proxy handler org.kaazing.gateway.service.proxy.ProxyServiceHandler@12bac8c: maximum.recovery.interval=0.
3291 org.kaazing.gateway.service.proxy.AbstractProxyHandler [Time-limited test] DEBUG AbstractProxyHandler - Proxy handler org.kaazing.gateway.service.proxy.ProxyServiceHandler@12bac8c: connect.strategy=IMMEDIATE.
3294 service.proxy [Time-limited test] DEBUG proxy - proxy service with thread alignment, using prepared.connection.count=0
3301 org.kaazing.gateway.server.context.resolve.DefaultAcceptOptionsContext [Time-limited test] TRACE DefaultAcceptOptionsContext - Accept options map = {supportedProtocols=[Ljava.lang.String;@2dfadff6, ws.inactivityTimeout=60000, ws[ws/rfc6455].ws[ws/rfc6455].inactivityTimeout=60000, ws[ws/draft-7x].ws[ws/draft-7x].inactivityTimeout=60000, ws.maxMessageSize=131072, ws[ws/rfc6455].ws[ws/rfc6455].maxMessageSize=131072, ws[ws/draft-7x].ws[ws/draft-7x].maxMessageSize=131072, http[http/1.1].keepAliveTimeout=60, http[x-kaazing-handshake].keepAliveTimeout=60, http[httpxe/1.1].keepAliveTimeout=60, http[httpxe/1.1].http[http/1.1].keepAliveTimeout=60, ssl.encryptionEnabled=true, socks.ssl.wantClientAuth=false, socks.ssl.needClientAuth=false, http.serverHeaderEnabled=true, ssl.wantClientAuth=false, ssl.needClientAuth=false, http[http/1.1].transport=socks://localhost:1080, tcp.maximumOutboundRate=4294967295, socks.retry.maximum.interval=1 second}
4365 transport.http.accept [Time-limited test] TRACE accept - binding: 'http://localhost:8081/;resource' null
4472 transport.tcp.accept [Time-limited test] DEBUG accept - TCP acceptor: worker count = 2
4543 org.kaazing.gateway.transport.nio.internal.AbstractNioAcceptor [Time-limited test] INFO AbstractNioAcceptor - Bound to resource: tcp://127.0.0.1:1080
4548 transport.http.accept [Time-limited test] TRACE accept - binding: 'http://localhost:8081/app1/;api' null
4880 transport.http.accept [Time-limited test] TRACE accept - binding: 'http://localhost:8081/;resource' ws/rfc6455
4941 transport.http.accept [Time-limited test] TRACE accept - binding: 'http://localhost:8081/app1' ws/rfc6455
5009 ha [Time-limited test] DEBUG ha - Exit Bind
5012 org.kaazing.gateway.server.context.resolve.DefaultConnectOptionsContext [Time-limited test] TRACE DefaultConnectOptionsContext - Connect options map = {protocolVersion=RFC_6455, ws.inactivityTimeout=0, http[http/1.1].keepAliveTimeout=30, http[http/1.1].keepAlive=true, ssl.encryptionEnabled=true, ssl.wantClientAuth=false, ssl.needClientAuth=false, socks.ssl.wantClientAuth=false, socks.ssl.needClientAuth=false}
5084 org.kaazing.gateway.server.Gateway [Time-limited test] INFO Gateway - Starting server
5084 org.kaazing.gateway.server.Gateway [Time-limited test] INFO Gateway - Starting services
5084 org.kaazing.gateway.server.Gateway [Time-limited test] INFO Gateway - wsn://localhost:8081/app1
5084 org.kaazing.gateway.server.Gateway [Time-limited test] INFO Gateway - Started services
5091 org.kaazing.gateway.server.Gateway [Time-limited test] INFO Gateway - Started server successfully in 1.801 secs at 2017-05-17 12:36:01
5092 ha [Time-limited test] DEBUG ha - Exit Gateway launcher initInternal
5115 transport.tcp.accept [New I/O server boss #3] DEBUG accept - nextWorker: returning worker #1 of 2
5928 org.kaazing.gateway.service.proxy.AbstractProxyHandler [New I/O worker #1] DEBUG AbstractProxyHandler - [1] session created ((#00000001: kaazing tcp, server, /127.0.0.1:34600 => /127.0.0.1:8080))
5961 transport.tcp.accept [New I/O worker #1] INFO accept - [tcp#1 127.0.0.1:34600] OPENED: (#00000001: kaazing tcp, server, /127.0.0.1:34600 => /127.0.0.1:8080)
5988 org.kaazing.gateway.server.context.resolve.DefaultConnectOptionsContext [New I/O worker #1] TRACE DefaultConnectOptionsContext - Connect options map = {protocolVersion=RFC_6455, ws.inactivityTimeout=60000, http[http/1.1].keepAliveTimeout=60, http[http/1.1].keepAlive=true, ssl.encryptionEnabled=true, ssl.wantClientAuth=false, ssl.needClientAuth=false, socks.ssl.wantClientAuth=false, socks.ssl.needClientAuth=false, http[http/1.1].transport=socks://localhost:1080, socks.timeout=5 seconds}
6654 transport.http.connect [New I/O worker #1] DEBUG connect - Cache miss - NO cached persistent connection: server = http://localhost:8081/app1
6700 transport.tcp.connect [New I/O worker #1] TRACE connect - AbstractNioConnector.init()
6701 transport.tcp.connect [New I/O worker #1] DEBUG connect - TCP connector: worker count = 2
6760 transport.tcp.connect [New I/O worker #1] TRACE connect - AbstractNioConnector.connectInternal(), resource: tcp://127.0.0.1:1080
6771 transport.tcp.accept [New I/O server boss #6] DEBUG accept - nextWorker: returning worker #1 of 2
6880 transport.tcp.accept [New I/O worker #1] TRACE accept - [tcp#1 127.0.0.1:34600] RECEIVED: HeapBuffer[pos=0 lim=11 cap=11: 63 6C 69 65 6E 74 20 64 61 74 61]
6947 transport.tcp.connect [New I/O worker #1] TRACE connect - AbstractNioConnector.connectInternal()$initializeSession(), session: (#00000003: kaazing tcp, client, /127.0.0.1:37114 => /127.0.0.1:1080), resource: tcp://127.0.0.1:1080
7360 transport.tcp.connect [New I/O worker #1] INFO connect - [tcp#3 127.0.0.1:37114] OPENED: (#00000003: kaazing tcp, client, /127.0.0.1:37114 => /127.0.0.1:1080)
7401 transport.socks.forward.connect [New I/O worker #1] TRACE connect - SOCKS i/o session (#00000003: kaazing tcp, client, /127.0.0.1:37114 => /127.0.0.1:1080), transition null --> GREETING_INITIATED
7516 transport.tcp.accept [New I/O worker #4] INFO accept - [tcp#2 127.0.0.1:37114] OPENED: (#00000002: kaazing tcp, server, /127.0.0.1:37114 => /127.0.0.1:1080)
7567 transport.socks.forward.accept [New I/O worker #4] TRACE accept - SOCKS i/o session (#00000002: kaazing tcp, server, /127.0.0.1:37114 => /127.0.0.1:1080), transition null --> EXPECTING_GREETING
7789 transport.tcp.connect [New I/O worker #1] TRACE connect - [tcp#3 127.0.0.1:37114] WRITE: com.kaazing.gateway.transport.socks.message.SocksConnectRequest[methods=[NONE]]
7963 transport.socks.forward.accept [New I/O worker #4] TRACE accept - [socks-forward-acceptor-#2] RECEIVED: HeapBuffer[pos=0 lim=3 cap=3: 05 01 00]
8006 transport.tcp.accept [New I/O worker #4] TRACE accept - [tcp#2 127.0.0.1:37114] RECEIVED: HeapBuffer[pos=0 lim=3 cap=3: 05 01 00]
8055 org.kaazing.mina.filter.codec.ProtocolCodecFilter [New I/O worker #4] DEBUG ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 2
8097 transport.socks.forward.accept [New I/O worker #4] TRACE accept - SOCKS i/o session (#00000002: kaazing tcp, server, /127.0.0.1:37114 => /127.0.0.1:1080) received handshake request: com.kaazing.gateway.transport.socks.message.SocksConnectRequest[methods=[NONE]]
8103 transport.socks.forward.accept [New I/O worker #4] TRACE accept - SOCKS i/o session (#00000002: kaazing tcp, server, /127.0.0.1:37114 => /127.0.0.1:1080), transition EXPECTING_GREETING --> EXPECTING_CONNECT_REQUEST
8149 transport.tcp.accept [New I/O worker #4] TRACE accept - [tcp#2 127.0.0.1:37114] WRITE: HeapBuffer[pos=0 lim=2 cap=2: 05 00]
8172 transport.socks.forward.accept [New I/O worker #4] TRACE accept - [socks-forward-acceptor-#2] WRITE: HeapBuffer[pos=0 lim=2 cap=2: 05 00]
8222 org.kaazing.mina.filter.codec.ProtocolCodecFilter [New I/O worker #1] DEBUG ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 3
8234 performance.tcp [New I/O worker #4] DEBUG tcp - AbstractNioWorker.process(Selector) took 295 ms: 1 reads, 0 writes
8243 transport.tcp.connect [New I/O worker #1] TRACE connect - [tcp#3 127.0.0.1:37114] RECEIVED: SocksConnectResponse[method=NONE]
8257 transport.socks.forward.connect [New I/O worker #1] TRACE connect - SOCKS i/o session (#00000003: kaazing tcp, client, /127.0.0.1:37114 => /127.0.0.1:1080) received handshake response: SocksConnectResponse[method=NONE]
8263 transport.socks.forward.connect [New I/O worker #1] TRACE connect - SOCKS i/o session (#00000003: kaazing tcp, client, /127.0.0.1:37114 => /127.0.0.1:1080), transition GREETING_INITIATED --> COMMAND_REQUESTED
8289 transport.tcp.connect [New I/O worker #1] TRACE connect - [tcp#3 127.0.0.1:37114] WRITE: com.kaazing.gateway.transport.socks.message.SocksCommandRequest[command=CONNECT_TCP_STREAM,uriAddress=http://localhost:8081/app1,port=8081,userID=]
8377 transport.socks.forward.accept [New I/O worker #4] TRACE accept - [socks-forward-acceptor-#2] RECEIVED: HeapBuffer[pos=0 lim=37 cap=37: 05 01 00 FE 00 00 00 1A 68 74 74 70 3A 2F 2F 6C...]
8378 performance.tcp [New I/O worker #1] DEBUG tcp - AbstractNioWorker.process(Selector) took 167 ms: 1 reads, 0 writes
8411 transport.tcp.accept [New I/O worker #4] TRACE accept - [tcp#2 127.0.0.1:37114] RECEIVED: HeapBuffer[pos=0 lim=37 cap=37: 05 01 00 FE 00 00 00 1A 68 74 74 70 3A 2F 2F 6C...]
8415 org.kaazing.mina.filter.codec.ProtocolCodecFilter [New I/O worker #4] DEBUG ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 2
8538 transport.socks.forward.accept [New I/O worker #4] TRACE accept - SOCKS i/o session (#00000002: kaazing tcp, server, /127.0.0.1:37114 => /127.0.0.1:1080) received socks command: com.kaazing.gateway.transport.socks.message.SocksCommandRequest[command=CONNECT_TCP_STREAM,uriAddress=http://localhost:8081/app1,port=8081,userID=null]
8648 transport.socks.forward.accept [New I/O worker #4] TRACE accept - SOCKS i/o session (#00000002: kaazing tcp, server, /127.0.0.1:37114 => /127.0.0.1:1080), transition EXPECTING_CONNECT_REQUEST --> CONNECTED
8682 transport.socks.forward.accept [New I/O worker #4] TRACE accept - Writing REQUEST_GRANTED on SOCKS i/o session (#00000002: kaazing tcp, server, /127.0.0.1:37114 => /127.0.0.1:1080)
8759 transport.tcp.accept [New I/O worker #4] TRACE accept - [tcp#2 127.0.0.1:37114] WRITE: HeapBuffer[pos=0 lim=37 cap=83: 05 00 00 FE 00 00 00 1A 68 74 74 70 3A 2F 2F 6C...]
8811 transport.socks.forward.accept [New I/O worker #4] TRACE accept - [socks-forward-acceptor-#2] WRITE: HeapBuffer[pos=0 lim=37 cap=83: 05 00 00 FE 00 00 00 1A 68 74 74 70 3A 2F 2F 6C...]
8851 org.kaazing.mina.filter.codec.ProtocolCodecFilter [New I/O worker #1] DEBUG ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 3
8941 transport.tcp.connect [New I/O worker #1] TRACE connect - [tcp#3 127.0.0.1:37114] RECEIVED: com.kaazing.gateway.transport.socks.message.SocksCommandResponse[status=REQUEST_GRANTED,address=null,uriAddress=http://localhost:8081/app1,port=8081]
8959 transport.socks.forward.connect [New I/O worker #1] TRACE connect - SOCKS i/o session (#00000003: kaazing tcp, client, /127.0.0.1:37114 => /127.0.0.1:1080) received command response: com.kaazing.gateway.transport.socks.message.SocksCommandResponse[status=REQUEST_GRANTED,address=null,uriAddress=http://localhost:8081/app1,port=8081]
8969 transport.socks.forward.connect [New I/O worker #1] TRACE connect - SOCKS i/o session (#00000003: kaazing tcp, client, /127.0.0.1:37114 => /127.0.0.1:1080), transition COMMAND_REQUESTED --> CONNECTED
9256 transport.socks.forward.accept [New I/O worker #4] TRACE accept - [(#00000002: kaazing tcp, server, /127.0.0.1:37114 => /127.0.0.1:1080)] received CONNECT_TCP_STREAM command. Initializing new Socks session.
9317 transport.socks.connect [New I/O worker #1] INFO connect - [socks#5 127.0.0.1:37114] OPENED: (#00000005: kzg socks, client, socks://internal:0 => socks://localhost:1080)
9442 transport.http.accept [New I/O worker #4] TRACE accept - Adding http accept bridge filters using nextProtocol: http/1.1
9968 performance.tcp [New I/O worker #4] DEBUG tcp - AbstractNioWorker.process(Selector) took 1614 ms: 1 reads, 0 writes
10217 transport.socks.connect [New I/O worker #1] TRACE connect - [socks#5 127.0.0.1:37114] WRITE: REQUEST: HTTP/1.1 GET /app1 HEADERS: {User-Agent=[Kaazing Gateway], Upgrade=[websocket], Sec-WebSocket-Version=[13], Sec-WebSocket-Key=[UznJYtHfQDfZrVXYE2W5BA==], Origin=[http://localhost:8081], Host=[localhost:8081], Connection=[Upgrade]} null
10715 transport.tcp.connect [New I/O worker #1] TRACE connect - [tcp#3 127.0.0.1:37114] WRITE: HeapBuffer[pos=0 lim=217 cap=1024: 47 45 54 20 2F 61 70 70 31 20 48 54 54 50 2F 31...]
10838 transport.tcp.accept [New I/O worker #4] TRACE accept - [tcp#2 127.0.0.1:37114] RECEIVED: HeapBuffer[pos=0 lim=217 cap=217: 47 45 54 20 2F 61 70 70 31 20 48 54 54 50 2F 31...]
10843 org.kaazing.mina.filter.codec.ProtocolCodecFilter [New I/O worker #4] DEBUG ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 2
10955 transport.socks.forward.accept [New I/O worker #4] TRACE accept - [socks-forward-acceptor-#2] RECEIVED: HeapBuffer[pos=0 lim=217 cap=217: 47 45 54 20 2F 61 70 70 31 20 48 54 54 50 2F 31...]
10972 org.kaazing.mina.filter.codec.ProtocolCodecFilter [New I/O worker #4] DEBUG ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 4
11084 transport.http.connect [New I/O worker #1] INFO connect - [http#6 127.0.0.1:37114] OPENED: (#00000006: kzg http, client, http://localhost:8081/app1 => http://localhost:8081/app1)
11194 performance.tcp [New I/O worker #1] DEBUG tcp - AbstractNioWorker.process(Selector) took 2345 ms: 1 reads, 0 writes
12118 transport.http.accept.mergeRequest [New I/O worker #4] TRACE mergeRequest - HttpMergeRequestFilter: Entering Request is '/app1
User-Agent: [Kaazing Gateway]
Upgrade: [websocket]
Sec-WebSocket-Version: [13]
Sec-WebSocket-Key: [UznJYtHfQDfZrVXYE2W5BA==]
Origin: [http://localhost:8081]
Host: [localhost:8081]
Connection: [Upgrade]
'.
13143 transport.http.accept.security [New I/O worker #4] TRACE security - HttpSubjectSecurityFilter skipped because no realm is configured.
13184 transport.socks.accept [New I/O worker #4] TRACE accept - [socks#4 127.0.0.1:37114] RECEIVED: REQUEST: HTTP/1.1 GET /app1 HEADERS: {X-Next-Protocol=[ws/rfc6455], User-Agent=[Kaazing Gateway], Upgrade=[websocket], Sec-WebSocket-Version=[13], Sec-WebSocket-Key=[UznJYtHfQDfZrVXYE2W5BA==], Origin=[http://localhost:8081], Host=[localhost:8081], Connection=[Upgrade]} null
13247 transport.http.accept [New I/O worker #4] INFO accept - [socks://internal:0 (socks://internal:0) http/1.1]
[tcp://127.0.0.1:37114 (tcp://127.0.0.1:37114) socks/5+] - [localhost:8081] "GET /app1 HTTP/1.1 " "Kaazing Gateway"
15614 transport.http.accept [New I/O worker #4] INFO accept - [http#7 127.0.0.1:37114] OPENED: (#00000007: kzg http, server, http://localhost:8081/app1 => http://localhost:8081/app1)
16945 transport.wsn.accept [New I/O worker #4] TRACE accept -
***Found local address for WS session:
[ws://localhost:8081/app1 (wsn://localhost:8081/app1)]
[http://localhost:8081/app1 (http://localhost:8081/app1) ws/rfc6455]
[socks://internal:0 (socks://localhost:1080) http/1.1]
[tcp://127.0.0.1:1080 (tcp://localhost:1080) socks/5+]
***via candidate:
[ws://localhost:8081/app1 (wsn://localhost:8081/app1)]
[http://localhost:8081/app1 (http://localhost:8081/app1) ws/rfc6455]
[socks://internal:0 (socks://localhost:1080) http/1.1]
[tcp://127.0.0.1:1080 (tcp://localhost:1080) socks/5+]
***with bindings {
[ws://localhost:8081/app1]=([ws://localhost:8081/app1],0x12bac8c,0x42623929,#1)
}
18739 transport.socks.accept [New I/O worker #4] TRACE accept - [socks#4 127.0.0.1:37114] WRITE: RESPONSE: 101 Web Socket Protocol Handshake HTTP/1.1 HEADERS: {Connection=[Upgrade], Date=[Wed, 17 May 2017 09:36:16 GMT], Sec-WebSocket-Accept=[Gj96gMBKZwWgGOVgcWA1zUYV2TU=], Server=[Kaazing Gateway], Upgrade=[websocket]} CONTENT: HeapBuffer[pos=0 lim=0 cap=0: empty]
21310 transport.socks.forward.accept [New I/O worker #4] TRACE accept - [socks-forward-acceptor-#2] WRITE: HeapBuffer[pos=0 lim=201 cap=1024: 48 54 54 50 2F 31 2E 31 20 31 30 31 20 57 65 62...]
21610 transport.tcp.accept [New I/O worker #4] TRACE accept - [tcp#2 127.0.0.1:37114] WRITE: HeapBuffer[pos=0 lim=201 cap=1024: 48 54 54 50 2F 31 2E 31 20 31 30 31 20 57 65 62...]
21997 transport.tcp.connect [New I/O worker #1] TRACE connect - [tcp#3 127.0.0.1:37114] RECEIVED: HeapBuffer[pos=0 lim=201 cap=201: 48 54 54 50 2F 31 2E 31 20 31 30 31 20 57 65 62...]
22010 org.kaazing.mina.filter.codec.ProtocolCodecFilter [New I/O worker #1] DEBUG ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 5
23022 transport.http.accept.mergeRequest [New I/O worker #4] TRACE mergeRequest - HttpMergeRequestFilter: response complete; removed merge request filter from session '(#00000004: kzg socks, server, socks://internal:0 => socks://localhost:1080)'.
23478 org.kaazing.gateway.transport.http.bridge.filter.HttpResponseDecodingState [New I/O worker #1] DEBUG HttpResponseDecodingState - "101 Web Socket Protocol Handshake HTTP/1.1"
23519 transport.socks.connect [New I/O worker #1] TRACE connect - [socks#5 127.0.0.1:37114] RECEIVED: RESPONSE: 101 Web Socket Protocol Handshake HTTP/1.1 HEADERS: {Connection=[Upgrade], Date=[Wed, 17 May 2017 09:36:16 GMT], Sec-WebSocket-Accept=[Gj96gMBKZwWgGOVgcWA1zUYV2TU=], Server=[Kaazing Gateway], Upgrade=[websocket]} null
23971 org.kaazing.gateway.transport.http.bridge.filter.HttpPersistenceFilter [New I/O worker #4] TRACE HttpPersistenceFilter - Setting idle timeout 60 on HTTP parent session (#00000004: kzg socks, server, socks://internal:0 => socks://localhost:1080)
23999 transport.wsn.connect [New I/O worker #1] TRACE connect - Using org.kaazing.gateway.transport.ws.CLOSE_TIMEOUT property of 5000 milliseconds for CLOSE frame timeouts
24085 transport.wsn.connect [New I/O worker #1] TRACE connect - WsCheckAliveFilter.setReadIdleTimeInMillis(30000)
24124 transport.wsn.connect [New I/O worker #1] DEBUG connect - Configured WebSocket inactivity timeout (ws.inactivity.timeout) is 60000 milliseconds
24848 transport.socks.accept [New I/O worker #4] TRACE accept - [socks#4 127.0.0.1:37114] WRITE: CONTENT: HeapBuffer[pos=0 lim=0 cap=0: empty]
25472 org.kaazing.gateway.service.proxy.AbstractProxyHandler [New I/O worker #1] DEBUG AbstractProxyHandler - [8] session created ((#00000008: kzg wsn, client, ws://localhost:8081/app1 => wsn://localhost:8081/app1))
25528 org.kaazing.gateway.service.proxy.ProxyServiceHandler [New I/O worker #1] TRACE ProxyServiceHandler - Connected to wsn://localhost:8081/app1 [(#00000001: kaazing tcp, server, /127.0.0.1:34600 => /127.0.0.1:8080)->(#00000008: kzg wsn, client, ws://localhost:8081/app1 => wsn://localhost:8081/app1)]
25535 org.kaazing.gateway.service.proxy.AbstractProxyHandler [New I/O worker #1] DEBUG AbstractProxyHandler - [1->8] attaching sessions
25672 org.kaazing.gateway.service.proxy.AbstractProxyHandler [New I/O worker #1] TRACE AbstractProxyHandler - [1->8] proxying message: HeapBuffer[pos=0 lim=11 cap=11: 63 6C 69 65 6E 74 20 64 61 74 61]
25897 transport.wsn.connect [New I/O worker #1] TRACE connect - [wsn#8 127.0.0.1:37114] WRITE: HeapBuffer[pos=0 lim=11 cap=11: 63 6C 69 65 6E 74 20 64 61 74 61]
26907 transport.socks.connect [New I/O worker #1] TRACE connect - [socks#5 127.0.0.1:37114] WRITE: HeapBuffer[pos=0 lim=17 cap=17: 82 8B B8 EF 49 DC DB 83 20 B9 D6 9B 69 B8 D9 9B...]
27481 transport.wsn.accept [New I/O worker #4] TRACE accept - Adding CLOSE frame filter for WS protocol version HYBI_13 for session (#00000004: kzg socks, server, socks://internal:0 => socks://localhost:1080)
27503 transport.tcp.connect [New I/O worker #1] TRACE connect - [tcp#3 127.0.0.1:37114] WRITE: HeapBuffer[pos=0 lim=17 cap=17: 82 8B B8 EF 49 DC DB 83 20 B9 D6 9B 69 B8 D9 9B...]
27550 transport.wsn.accept [New I/O worker #4] TRACE accept - Using org.kaazing.gateway.transport.ws.CLOSE_TIMEOUT property of 5000 milliseconds for CLOSE frame timeouts
27812 transport.wsn.accept [New I/O worker #4] TRACE accept - WsCheckAliveFilter.setReadIdleTimeInMillis(30000)
27885 transport.wsn.accept [New I/O worker #4] DEBUG accept - Configured WebSocket inactivity timeout (ws.inactivity.timeout) is 60000 milliseconds
28905 transport.wsn.connect [New I/O worker #1] INFO connect - [wsn#8 127.0.0.1:37114] OPENED: (#00000008: kzg wsn, client, ws://localhost:8081/app1 => wsn://localhost:8081/app1)
29205 transport.http.connect [New I/O worker #1] INFO connect - [http#6 127.0.0.1:37114] CLOSED: (#00000006: kzg http, client, http://localhost:8081/app1 => http://localhost:8081/app1)
29466 performance.tcp [New I/O worker #1] DEBUG tcp - AbstractNioWorker.process(Selector) took 7515 ms: 1 reads, 0 writes
32444 org.kaazing.gateway.service.proxy.AbstractProxyHandler [New I/O worker #4] DEBUG AbstractProxyHandler - [9] session created ((#00000009: kzg wsn, server, ws://localhost:8081/app1 => wsn://localhost:8081/app1))
32766 transport.wsn.accept [New I/O worker #4] INFO accept - [wsn#9 127.0.0.1:37114] OPENED: (#00000009: kzg wsn, server, ws://localhost:8081/app1 => wsn://localhost:8081/app1)
32887 org.kaazing.gateway.server.context.resolve.DefaultConnectOptionsContext [New I/O worker #4] TRACE DefaultConnectOptionsContext - Connect options map = {protocolVersion=RFC_6455, ws.inactivityTimeout=0, http[http/1.1].keepAliveTimeout=30, http[http/1.1].keepAlive=true, ssl.encryptionEnabled=true, ssl.wantClientAuth=false, ssl.needClientAuth=false, socks.ssl.wantClientAuth=false, socks.ssl.needClientAuth=false}
33914 transport.tcp.connect [New I/O worker #4] TRACE connect - AbstractNioConnector.init()
33973 transport.tcp.connect [New I/O worker #4] DEBUG connect - TCP connector: worker count = 2
34417 transport.tcp.connect [New I/O worker #4] TRACE connect - AbstractNioConnector.connectInternal(), resource: tcp://127.0.0.1:3101
34906 transport.http.accept [New I/O worker #4] INFO accept - [http#7 127.0.0.1:37114] CLOSED: (#00000007: kzg http, server, http://localhost:8081/app1 => http://localhost:8081/app1)
35360 performance.tcp [New I/O worker #4] DEBUG tcp - AbstractNioWorker.process(Selector) took 24550 ms: 1 reads, 0 writes
35377 transport.tcp.connect [New I/O worker #4] TRACE connect - AbstractNioConnector.connectInternal()$initializeSession(), session: (#00000010: kaazing tcp, client, /127.0.0.1:34228 => /127.0.0.1:3101), resource: tcp://127.0.0.1:3101
35481 org.kaazing.gateway.service.proxy.AbstractProxyHandler [New I/O worker #4] DEBUG AbstractProxyHandler - [10] session created ((#00000010: kaazing tcp, client, /127.0.0.1:34228 => /127.0.0.1:3101))
35607 org.kaazing.gateway.service.proxy.ProxyServiceHandler [New I/O worker #4] TRACE ProxyServiceHandler - Connected to tcp://localhost:3101 [(#00000009: kzg wsn, server, ws://localhost:8081/app1 => wsn://localhost:8081/app1)->(#00000010: kaazing tcp, client, /127.0.0.1:34228 => /127.0.0.1:3101)]
35611 org.kaazing.gateway.service.proxy.AbstractProxyHandler [New I/O worker #4] DEBUG AbstractProxyHandler - [9->10] attaching sessions
35640 transport.tcp.connect [New I/O worker #4] INFO connect - [tcp#10 127.0.0.1:34228] OPENED: (#00000010: kaazing tcp, client, /127.0.0.1:34228 => /127.0.0.1:3101)
35687 transport.tcp.accept [New I/O worker #4] TRACE accept - [tcp#2 127.0.0.1:37114] RECEIVED: HeapBuffer[pos=0 lim=17 cap=17: 82 8B B8 EF 49 DC DB 83 20 B9 D6 9B 69 B8 D9 9B...]
35689 org.kaazing.mina.filter.codec.ProtocolCodecFilter [New I/O worker #4] DEBUG ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 2
35715 transport.socks.forward.accept [New I/O worker #4] TRACE accept - [socks-forward-acceptor-#2] RECEIVED: HeapBuffer[pos=0 lim=17 cap=17: 82 8B B8 EF 49 DC DB 83 20 B9 D6 9B 69 B8 D9 9B...]
35741 org.kaazing.mina.filter.codec.ProtocolCodecFilter [New I/O worker #4] DEBUG ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 4
35809 transport.socks.accept [New I/O worker #4] TRACE accept - [socks#4 127.0.0.1:37114] RECEIVED: BINARY: 63 6C 69 65 6E 74 20 64 61 74 61
35917 transport.wsn.accept [New I/O worker #4] TRACE accept - [wsn#9 127.0.0.1:37114] RECEIVED: HeapBuffer[pos=0 lim=11 cap=11: 63 6C 69 65 6E 74 20 64 61 74 61]
36005 org.kaazing.gateway.service.proxy.AbstractProxyHandler [New I/O worker #4] TRACE AbstractProxyHandler - [9->10] proxying message: HeapBuffer[pos=0 lim=11 cap=11: 63 6C 69 65 6E 74 20 64 61 74 61]
36215 transport.tcp.connect [New I/O worker #4] TRACE connect - [tcp#10 127.0.0.1:34228] WRITE: HeapBuffer[pos=0 lim=11 cap=11: 63 6C 69 65 6E 74 20 64 61 74 61]
36469 performance.tcp [New I/O worker #4] DEBUG tcp - AbstractNioWorker.process(Selector) took 810 ms: 1 reads, 0 writes
37215 transport.http.accept [Time-limited test] TRACE accept - unbinding: 'http://localhost:8081/;resource' null
37320 transport.http.accept [Time-limited test] TRACE accept - unbinding: 'http://localhost:8081/app1/;api' null
37946 transport.http.accept [Time-limited test] TRACE accept - unbinding: 'http://localhost:8081/;resource' ws/rfc6455
38017 transport.http.accept [Time-limited test] TRACE accept - unbinding: 'http://localhost:8081/app1' ws/rfc6455
dpwspoon
@Anisotrop can you provide a sample output before and after, thanks!