Skip to main content

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[jetty-users] WebSocketServlet in a WAR-file: Bad character 13 for SelectChannelEndPoint

Godd afternoon,

I am probably missing something simple, when trying to create a WAR-file with a custom WebSocketServlet with a test project I have created at https://github.com/afarber/jetty-newbie/tree/master/WebSocketWarApp

WsServlet.java

public class WsServlet extends WebSocketServlet
{
    @Override
    public void configure(WebSocketServletFactory factory) {
        factory.register(EchoListener.class);
    }
}

EchoListener.java

public class EchoListener implements WebSocketListener {
    private static final Logger LOG = Log.getLogger(EchoListener.class);
    private Session mSession;

    @Override
    public void onWebSocketConnect(Session session) {
        LOG.info("onWebSocketConnect {}", session);
        mSession = session;
    }

    @Override
    public void onWebSocketText(String message) {
        LOG.info("onWebSocketText {}", message);
        if (mSession != null && mSession.isOpen()) {
            mSession.getRemote().sendString("ECHO: " + message, null);
        }
    }
}

I have created Jetty base dir with:

# java -jar /Users/afarber/jetty-distribution-9.3.10.v20160621/start.jar jetty.home=/Users/afarber/jetty-distribution-9.3.10.v20160621 jetty.base=/Users/afarber/jetty-base --add-to-startd=http,servlet,webapp,resources,ext,fcgi,websocket,proxy-protocol

INFO: ext             initialised in ${jetty.base}/start.d/ext.ini
INFO: resources       initialised in ${jetty.base}/start.d/resources.ini
INFO: server          initialised (transitively) in ${jetty.base}/start.d/server.ini
INFO: http            initialised in ${jetty.base}/start.d/http.ini
INFO: servlet         initialised in ${jetty.base}/start.d/servlet.ini
INFO: fcgi            initialised in ${jetty.base}/start.d/fcgi.ini
INFO: proxy-protocol  initialised in ${jetty.base}/start.d/proxy-protocol.ini
INFO: webapp          initialised in ${jetty.base}/start.d/webapp.ini
INFO: websocket       initialised in ${jetty.base}/start.d/websocket.ini
MKDIR: ${jetty.base}/lib
MKDIR: ${jetty.base}/lib/ext
MKDIR: ${jetty.base}/resources
MKDIR: ${jetty.base}/webapps
INFO: Base directory was modified

And copied the WAR-file to $JETTY_BASE/webapps/ws-servlet-0.1-SNAPSHOT.war

Finally I have created $JETTY_BASE/webapps/ws.xml pointing to the WAR-file:

<?xml version="1.0" encoding="UTF-8"?>
<!DOCTYPE Configure PUBLIC "-//Jetty//Configure//EN" 
<Configure class="org.eclipse.jetty.webapp.WebAppContext">

    <Set name="contextPath">/ws</Set>
    <Set name="war"><SystemProperty name="jetty.base"/>/webapps/ws-servlet-0.1-SNAPSHOT.war</Set>
</Configure>

And started Jetty with:

# java -Dorg.eclipse.jetty.LEVEL=DEBUG -jar /Users/afarber/jetty-distribution-9.3.10.v20160621/start.jar jetty.base=/Users/afarber/jetty-base

When I try to connect using simple _javascript_ code in browser or the "Simple Web Socket Client extension" for Chrome I get the error:

WARN:oejs.ProxyConnectionFactory:qtp1993134103-12: Bad character 13 for SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,-,-,0/30000,ProxyConnection@49376d8e}{io=1/0,kio=1,kro=1}

Here is the full log (also attached):

https://gist.github.com/afarber/2865a241912d5d359e0db428ed38f46b

Also I have written the problem down at
http://stackoverflow.com/questions/38161379/websocketservlet-in-a-war-file-bad-character-13-for-selectchannelendpoint

Thank you
Alex

# java -Dorg.eclipse.jetty.LEVEL=DEBUG -jar /Users/afarber/jetty-distribution-9.3.10.v20160621/start.jar jetty.base=/Users/afarber/jetty-base

2016-07-02 16:24:54.864:INFO::main: Logging initialized @487ms
2016-07-02 16:24:54.903:DBUG:oejx.XmlParser:main: parse: file:/Users/afarber/jetty-distribution-9.3.10.v20160621/etc/jetty.xml
2016-07-02 16:24:54.906:DBUG:oejx.XmlParser:main: parsing: sid=file:/Users/afarber/jetty-distribution-9.3.10.v20160621/etc/jetty.xml,pid=null
2016-07-02 16:24:54.916:DBUG:oejx.XmlParser:main: resolveEntity(-//Jetty//Configure//EN, http://www.eclipse.org/jetty/configure_9_3.dtd)
2016-07-02 16:24:54.916:DBUG:oejx.XmlParser:main: Redirected entity http://www.eclipse.org/jetty/configure_9_3.dtd --> jar:file:/Users/afarber/jetty-distribution-9.3.10.v20160621/lib/jetty-xml-9.3.10.v20160621.jar!/org/eclipse/jetty/xml/configure_9_3.dtd
2016-07-02 16:24:54.978:DBUG:oejuc.ContainerLifeCycle:main: org.eclipse.jetty.server.Server@5aaa6d82 added {qtp1993134103{STOPPED,8<=0<=200,i=0,q=0},AUTO}
2016-07-02 16:24:54.979:DBUG:oejx.XmlConfiguration:main: XML get ThreadPool
2016-07-02 16:24:54.984:DBUG:oejx.XmlConfiguration:main: XML qtp1993134103{STOPPED,8<=0<=200,i=0,q=0}.setMinThreads(10)
2016-07-02 16:24:54.986:DBUG:oejx.XmlConfiguration:main: XML qtp1993134103{STOPPED,10<=0<=200,i=0,q=0}.setMaxThreads(200)
2016-07-02 16:24:54.986:DBUG:oejx.XmlConfiguration:main: XML qtp1993134103{STOPPED,10<=0<=200,i=0,q=0}.setIdleTimeout(60000)
2016-07-02 16:24:54.987:DBUG:oejx.XmlConfiguration:main: XML qtp1993134103{STOPPED,10<=0<=200,i=0,q=0}.setDetailedDump(false)
2016-07-02 16:24:54.988:DBUG:oejx.XmlConfiguration:main: XML new org.eclipse.jetty.util.thread.ScheduledExecutorScheduler
2016-07-02 16:24:54.989:DBUG:oejx.XmlConfiguration:main: using normal mapping
2016-07-02 16:24:54.990:DBUG:oejx.XmlConfiguration:main: XML call addBean
2016-07-02 16:24:54.994:DBUG:oejuc.ContainerLifeCycle:main: org.eclipse.jetty.server.Server@5aaa6d82 added {org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@2f333739,AUTO}
2016-07-02 16:24:54.995:DBUG:oejx.XmlConfiguration:main: XML new org.eclipse.jetty.server.HttpConfiguration
2016-07-02 16:24:54.997:DBUG:oejx.XmlConfiguration:main: using normal mapping
2016-07-02 16:24:55.003:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:0,[]}.setSecureScheme(https)
2016-07-02 16:24:55.004:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:0,[]}.setSecurePort(8443)
2016-07-02 16:24:55.005:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:8443,[]}.setOutputBufferSize(32768)
2016-07-02 16:24:55.006:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:8443,[]}.setOutputAggregationSize(8192)
2016-07-02 16:24:55.006:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:8443,[]}.setRequestHeaderSize(8192)
2016-07-02 16:24:55.007:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:8443,[]}.setResponseHeaderSize(8192)
2016-07-02 16:24:55.008:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:8443,[]}.setSendServerVersion(true)
2016-07-02 16:24:55.008:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:8443,[]}.setSendDateHeader(false)
2016-07-02 16:24:55.009:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:8443,[]}.setHeaderCacheSize(512)
2016-07-02 16:24:55.010:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:8443,[]}.setDelayDispatchUntilContent(true)
2016-07-02 16:24:55.010:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:8443,[]}.setMaxErrorDispatches(10)
2016-07-02 16:24:55.011:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:8443,[]}.setBlockingTimeout(-1)
2016-07-02 16:24:55.011:DBUG:oejx.XmlConfiguration:main: XML HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:8443,[]}.setPersistentConnectionsEnabled(true)
2016-07-02 16:24:55.012:DBUG:oejx.XmlConfiguration:main: XML new org.eclipse.jetty.server.handler.HandlerCollection
2016-07-02 16:24:55.012:DBUG:oejx.XmlConfiguration:main: using normal mapping
2016-07-02 16:24:55.014:DBUG:oejx.XmlConfiguration:main: XML new org.eclipse.jetty.server.handler.ContextHandlerCollection
2016-07-02 16:24:55.015:DBUG:oejx.XmlConfiguration:main: using normal mapping
2016-07-02 16:24:55.020:DBUG:oejx.XmlConfiguration:main: XML new org.eclipse.jetty.server.handler.DefaultHandler
2016-07-02 16:24:55.021:DBUG:oejx.XmlConfiguration:main: using normal mapping
2016-07-02 16:24:55.031:DBUG:oejx.XmlConfiguration:main: XML org.eclipse.jetty.server.handler.HandlerCollection@1996cd68[].setHandlers([Lorg.eclipse.jetty.server.Handler;@3339ad8e)
2016-07-02 16:24:55.031:DBUG:oejuc.ContainerLifeCycle:main: org.eclipse.jetty.server.handler.HandlerCollection@1996cd68[org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[], org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4] added {org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[],AUTO}
2016-07-02 16:24:55.032:DBUG:oejuc.ContainerLifeCycle:main: org.eclipse.jetty.server.handler.HandlerCollection@1996cd68[org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[], org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4] added {org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4,AUTO}
2016-07-02 16:24:55.032:DBUG:oejx.XmlConfiguration:main: XML org.eclipse.jetty.server.Server@5aaa6d82.setHandler(org.eclipse.jetty.server.handler.HandlerCollection@1996cd68[org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[], org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4])
2016-07-02 16:24:55.032:DBUG:oejuc.ContainerLifeCycle:main: org.eclipse.jetty.server.Server@5aaa6d82 added {org.eclipse.jetty.server.handler.HandlerCollection@1996cd68[org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[], org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4],MANAGED}
2016-07-02 16:24:55.033:DBUG:oejx.XmlConfiguration:main: XML org.eclipse.jetty.server.Server@5aaa6d82.setStopAtShutdown(true)
2016-07-02 16:24:55.033:DBUG:oejx.XmlConfiguration:main: XML org.eclipse.jetty.server.Server@5aaa6d82.setStopTimeout(5000)
2016-07-02 16:24:55.034:DBUG:oejx.XmlConfiguration:main: XML org.eclipse.jetty.server.Server@5aaa6d82.setDumpAfterStart(false)
2016-07-02 16:24:55.034:DBUG:oejx.XmlConfiguration:main: XML org.eclipse.jetty.server.Server@5aaa6d82.setDumpBeforeStop(false)
2016-07-02 16:24:55.035:DBUG:oejx.XmlParser:main: parse: file:/Users/afarber/jetty-distribution-9.3.10.v20160621/etc/jetty-http.xml
2016-07-02 16:24:55.035:DBUG:oejx.XmlParser:main: parsing: sid=file:/Users/afarber/jetty-distribution-9.3.10.v20160621/etc/jetty-http.xml,pid=null
2016-07-02 16:24:55.036:DBUG:oejx.XmlParser:main: resolveEntity(-//Jetty//Configure//EN, http://www.eclipse.org/jetty/configure_9_3.dtd)
2016-07-02 16:24:55.036:DBUG:oejx.XmlParser:main: Redirected entity http://www.eclipse.org/jetty/configure_9_3.dtd --> jar:file:/Users/afarber/jetty-distribution-9.3.10.v20160621/lib/jetty-xml-9.3.10.v20160621.jar!/org/eclipse/jetty/xml/configure_9_3.dtd
2016-07-02 16:24:55.047:DBUG:oejx.XmlConfiguration:main: XML new org.eclipse.jetty.server.ServerConnector
2016-07-02 16:24:55.051:DBUG:oejx.XmlConfiguration:main: XML new org.eclipse.jetty.server.HttpConnectionFactory
2016-07-02 16:24:55.053:DBUG:oejx.XmlConfiguration:main: XML call valueOf
2016-07-02 16:24:55.054:DBUG:oejx.XmlConfiguration:main: using named mapping
2016-07-02 16:24:55.074:DBUG:oeju.TypeUtil:main: placing named config in position 0
2016-07-02 16:24:55.076:DBUG:oeju.TypeUtil:main: placing named compliance in position 1
2016-07-02 16:24:55.077:DBUG:oejuc.ContainerLifeCycle:main: HttpConnectionFactory@33e5ccce[HTTP/1.1] added {HttpConfiguration@1c6b6478{32768/8192,8192/8192,https://:8443,[]},POJO}
2016-07-02 16:24:55.078:DBUG:oejx.XmlConfiguration:main: using named mapping
2016-07-02 16:24:55.086:DBUG:oeju.TypeUtil:main: placing named server in position 0
2016-07-02 16:24:55.088:DBUG:oeju.TypeUtil:main: placing named acceptors in position 1
2016-07-02 16:24:55.088:DBUG:oeju.TypeUtil:main: placing named selectors in position 2
2016-07-02 16:24:55.088:DBUG:oeju.TypeUtil:main: placing [Lorg.eclipse.jetty.server.ConnectionFactory;@2d6d8735 in position 3
2016-07-02 16:24:55.089:DBUG:oeju.TypeUtil:main: placing named server in position 0
2016-07-02 16:24:55.089:DBUG:oeju.TypeUtil:main: placing named acceptors in position 1
2016-07-02 16:24:55.089:DBUG:oeju.TypeUtil:main: placing named selectors in position 2
2016-07-02 16:24:55.090:DBUG:oeju.TypeUtil:main: placing named factories in position 3
2016-07-02 16:24:55.096:DBUG:oejuc.ContainerLifeCycle:main: ServerConnector@763d9750{null,[]}{0.0.0.0:0} added {org.eclipse.jetty.server.Server@5aaa6d82,UNMANAGED}
2016-07-02 16:24:55.097:DBUG:oejuc.ContainerLifeCycle:main: ServerConnector@763d9750{null,[]}{0.0.0.0:0} added {qtp1993134103{STOPPED,10<=0<=200,i=0,q=0},AUTO}
2016-07-02 16:24:55.097:DBUG:oejuc.ContainerLifeCycle:main: ServerConnector@763d9750{null,[]}{0.0.0.0:0} added {org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@2f333739,AUTO}
2016-07-02 16:24:55.097:DBUG:oejuc.ContainerLifeCycle:main: ServerConnector@763d9750{null,[]}{0.0.0.0:0} added {org.eclipse.jetty.io.ArrayByteBufferPool@5c0369c4,POJO}
2016-07-02 16:24:55.098:DBUG:oejuc.ContainerLifeCycle:main: ServerConnector@763d9750{null,[http/1.1]}{0.0.0.0:0} added {HttpConnectionFactory@33e5ccce[HTTP/1.1],AUTO}
2016-07-02 16:24:55.098:DBUG:oejs.AbstractConnector:main: ServerConnector@763d9750{HTTP/1.1,[http/1.1]}{0.0.0.0:0} added HttpConnectionFactory@33e5ccce[HTTP/1.1]
2016-07-02 16:24:55.103:DBUG:oejuc.ContainerLifeCycle:main: ServerConnector@763d9750{HTTP/1.1,[http/1.1]}{0.0.0.0:0} added {org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@629f0666,MANAGED}
2016-07-02 16:24:55.104:DBUG:oejx.XmlConfiguration:main: XML ServerConnector@763d9750{HTTP/1.1,[http/1.1]}{0.0.0.0:0}.setHost(null)
2016-07-02 16:24:55.106:DBUG:oejx.XmlConfiguration:main: XML ServerConnector@763d9750{HTTP/1.1,[http/1.1]}{0.0.0.0:0}.setPort(8080)
2016-07-02 16:24:55.107:DBUG:oejx.XmlConfiguration:main: XML ServerConnector@763d9750{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}.setIdleTimeout(30000)
2016-07-02 16:24:55.107:DBUG:oejx.XmlConfiguration:main: XML ServerConnector@763d9750{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}.setSoLingerTime(-1)
2016-07-02 16:24:55.108:DBUG:oejx.XmlConfiguration:main: XML ServerConnector@763d9750{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}.setAcceptorPriorityDelta(0)
2016-07-02 16:24:55.109:DBUG:oejx.XmlConfiguration:main: XML ServerConnector@763d9750{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}.setAcceptQueueSize(0)
2016-07-02 16:24:55.109:DBUG:oejx.XmlConfiguration:main: XML call addConnector
2016-07-02 16:24:55.109:DBUG:oejuc.ContainerLifeCycle:main: org.eclipse.jetty.server.Server@5aaa6d82 added {ServerConnector@763d9750{HTTP/1.1,[http/1.1]}{0.0.0.0:8080},AUTO}
2016-07-02 16:24:55.110:DBUG:oejx.XmlParser:main: parse: file:/Users/afarber/jetty-distribution-9.3.10.v20160621/etc/jetty-proxy-protocol.xml
2016-07-02 16:24:55.110:DBUG:oejx.XmlParser:main: parsing: sid=file:/Users/afarber/jetty-distribution-9.3.10.v20160621/etc/jetty-proxy-protocol.xml,pid=null
2016-07-02 16:24:55.111:DBUG:oejx.XmlParser:main: resolveEntity(-//Jetty//Configure//EN, http://www.eclipse.org/jetty/configure_9_3.dtd)
2016-07-02 16:24:55.111:DBUG:oejx.XmlParser:main: Redirected entity http://www.eclipse.org/jetty/configure_9_3.dtd --> jar:file:/Users/afarber/jetty-distribution-9.3.10.v20160621/lib/jetty-xml-9.3.10.v20160621.jar!/org/eclipse/jetty/xml/configure_9_3.dtd
2016-07-02 16:24:55.118:DBUG:oejx.XmlConfiguration:main: XML new org.eclipse.jetty.server.ProxyConnectionFactory
2016-07-02 16:24:55.119:DBUG:oejx.XmlConfiguration:main: using normal mapping
2016-07-02 16:24:55.119:DBUG:oejx.XmlConfiguration:main: XML call addFirstConnectionFactory
2016-07-02 16:24:55.119:DBUG:oejuc.ContainerLifeCycle:main: ServerConnector@763d9750{HTTP/1.1,[proxy]}{0.0.0.0:8080} added {ProxyConnectionFactory@13b6d03[proxy],AUTO}
2016-07-02 16:24:55.120:DBUG:oejs.AbstractConnector:main: ServerConnector@763d9750{HTTP/1.1,[proxy]}{0.0.0.0:8080} added ProxyConnectionFactory@13b6d03[proxy]
2016-07-02 16:24:55.120:DBUG:oejs.AbstractConnector:main: ServerConnector@763d9750{HTTP/1.1,[proxy, http/1.1]}{0.0.0.0:8080} added HttpConnectionFactory@33e5ccce[HTTP/1.1]
2016-07-02 16:24:55.121:DBUG:oejx.XmlParser:main: parse: file:/Users/afarber/jetty-distribution-9.3.10.v20160621/etc/jetty-plus.xml
2016-07-02 16:24:55.121:DBUG:oejx.XmlParser:main: parsing: sid=file:/Users/afarber/jetty-distribution-9.3.10.v20160621/etc/jetty-plus.xml,pid=null
2016-07-02 16:24:55.122:DBUG:oejx.XmlParser:main: resolveEntity(-//Jetty//Configure//EN, http://www.eclipse.org/jetty/configure_9_3.dtd)
2016-07-02 16:24:55.122:DBUG:oejx.XmlParser:main: Redirected entity http://www.eclipse.org/jetty/configure_9_3.dtd --> jar:file:/Users/afarber/jetty-distribution-9.3.10.v20160621/lib/jetty-xml-9.3.10.v20160621.jar!/org/eclipse/jetty/xml/configure_9_3.dtd
2016-07-02 16:24:55.130:DBUG:oejx.XmlConfiguration:main: XML call setServerDefault
2016-07-02 16:24:55.165:DBUG:oejuc.ContainerLifeCycle:main: org.eclipse.jetty.server.Server@5aaa6d82 added {[org.eclipse.jetty.webapp.WebInfConfiguration, org.eclipse.jetty.webapp.WebXmlConfiguration, org.eclipse.jetty.webapp.MetaInfConfiguration, org.eclipse.jetty.webapp.FragmentConfiguration, org.eclipse.jetty.webapp.JettyWebXmlConfiguration],POJO}
2016-07-02 16:24:55.166:DBUG:oejx.XmlConfiguration:main: XML call addAfter
2016-07-02 16:24:55.167:DBUG:oejx.XmlParser:main: parse: file:/Users/afarber/jetty-distribution-9.3.10.v20160621/etc/jetty-annotations.xml
2016-07-02 16:24:55.167:DBUG:oejx.XmlParser:main: parsing: sid=file:/Users/afarber/jetty-distribution-9.3.10.v20160621/etc/jetty-annotations.xml,pid=null
2016-07-02 16:24:55.167:DBUG:oejx.XmlParser:main: resolveEntity(-//Jetty//Configure//EN, http://www.eclipse.org/jetty/configure_9_3.dtd)
2016-07-02 16:24:55.168:DBUG:oejx.XmlParser:main: Redirected entity http://www.eclipse.org/jetty/configure_9_3.dtd --> jar:file:/Users/afarber/jetty-distribution-9.3.10.v20160621/lib/jetty-xml-9.3.10.v20160621.jar!/org/eclipse/jetty/xml/configure_9_3.dtd
2016-07-02 16:24:55.175:DBUG:oejx.XmlConfiguration:main: XML call setServerDefault
2016-07-02 16:24:55.176:DBUG:oejx.XmlConfiguration:main: XML call addBefore
2016-07-02 16:24:55.176:DBUG:oejuc.AbstractLifeCycle:main: starting org.eclipse.jetty.server.Server@5aaa6d82
2016-07-02 16:24:55.179:INFO:oejs.Server:main: jetty-9.3.10.v20160621
2016-07-02 16:24:55.201:DBUG:oejsh.AbstractHandler:main: starting org.eclipse.jetty.server.Server@5aaa6d82
2016-07-02 16:24:55.201:DBUG:oejuc.AbstractLifeCycle:main: starting qtp1993134103{STOPPED,10<=0<=200,i=0,q=0}
2016-07-02 16:24:55.204:DBUG:oejuc.AbstractLifeCycle:main: STARTED @828ms qtp1993134103{STARTED,10<=10<=200,i=10,q=0}
2016-07-02 16:24:55.204:DBUG:oejuc.AbstractLifeCycle:main: starting org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@2f333739
2016-07-02 16:24:55.207:DBUG:oejuc.AbstractLifeCycle:main: STARTED @831ms org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@2f333739
2016-07-02 16:24:55.207:DBUG:oejuc.AbstractLifeCycle:main: starting org.eclipse.jetty.server.handler.HandlerCollection@1996cd68[org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[], org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4]
2016-07-02 16:24:55.207:DBUG:oejsh.AbstractHandler:main: starting org.eclipse.jetty.server.handler.HandlerCollection@1996cd68[org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[], org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4]
2016-07-02 16:24:55.207:DBUG:oejuc.AbstractLifeCycle:main: starting org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[]
2016-07-02 16:24:55.207:DBUG:oejsh.AbstractHandler:main: starting org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[]
2016-07-02 16:24:55.207:DBUG:oejuc.AbstractLifeCycle:main: STARTED @831ms org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[]
2016-07-02 16:24:55.208:DBUG:oejuc.AbstractLifeCycle:main: starting org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4
2016-07-02 16:24:55.208:DBUG:oejsh.AbstractHandler:main: starting org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4
2016-07-02 16:24:55.208:DBUG:oejuc.AbstractLifeCycle:main: STARTED @831ms org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4
2016-07-02 16:24:55.208:DBUG:oejuc.AbstractLifeCycle:main: STARTED @831ms org.eclipse.jetty.server.handler.HandlerCollection@1996cd68[org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[], org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4]
2016-07-02 16:24:55.208:DBUG:oejuc.AbstractLifeCycle:main: starting ServerConnector@763d9750{proxy,[proxy, http/1.1]}{0.0.0.0:8080}
2016-07-02 16:24:55.224:DBUG:oejuc.ContainerLifeCycle:main: ServerConnector@763d9750{proxy,[proxy, http/1.1]}{0.0.0.0:8080} added {sun.nio.ch.ServerSocketChannelImpl[/0:0:0:0:0:0:0:0:8080],POJO}
2016-07-02 16:24:55.225:DBUG:oejuc.AbstractLifeCycle:main: starting HttpConnectionFactory@33e5ccce[HTTP/1.1]
2016-07-02 16:24:55.225:DBUG:oejuc.AbstractLifeCycle:main: STARTED @849ms HttpConnectionFactory@33e5ccce[HTTP/1.1]
2016-07-02 16:24:55.225:DBUG:oejuc.AbstractLifeCycle:main: starting org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@629f0666
2016-07-02 16:24:55.231:DBUG:oejuc.ContainerLifeCycle:main: org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@629f0666 added {org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=-1 selected=-1,AUTO}
2016-07-02 16:24:55.231:DBUG:oejuc.ContainerLifeCycle:main: org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@629f0666 added {org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=-1 selected=-1,AUTO}
2016-07-02 16:24:55.232:DBUG:oejuc.AbstractLifeCycle:main: starting org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=-1 selected=-1
2016-07-02 16:24:55.237:DBUG:oejut.QueuedThreadPool:main: queue org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=0 selected=0
2016-07-02 16:24:55.237:DBUG:oejuc.AbstractLifeCycle:main: STARTED @861ms org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=0 selected=0
2016-07-02 16:24:55.237:DBUG:oejut.QueuedThreadPool:qtp1993134103-10: run org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=0 selected=0
2016-07-02 16:24:55.238:DBUG:oejuc.AbstractLifeCycle:main: starting org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=-1 selected=-1
2016-07-02 16:24:55.238:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-10: EPC Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 execute
2016-07-02 16:24:55.238:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-10: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 produce enter
2016-07-02 16:24:55.238:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-10: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 producing
2016-07-02 16:24:55.239:DBUG:oeji.ManagedSelector:qtp1993134103-10: Selector loop waiting on select
2016-07-02 16:24:55.238:DBUG:oejut.QueuedThreadPool:main: queue org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=0 selected=0
2016-07-02 16:24:55.240:DBUG:oejut.QueuedThreadPool:qtp1993134103-11: run org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=0 selected=0
2016-07-02 16:24:55.240:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-11: EPC Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@4b37ade9 execute
2016-07-02 16:24:55.240:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-11: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@4b37ade9 produce enter
2016-07-02 16:24:55.240:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-11: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@4b37ade9 producing
2016-07-02 16:24:55.240:DBUG:oeji.ManagedSelector:qtp1993134103-11: Selector loop waiting on select
2016-07-02 16:24:55.240:DBUG:oejuc.AbstractLifeCycle:main: STARTED @864ms org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=0 selected=0
2016-07-02 16:24:55.241:DBUG:oejuc.AbstractLifeCycle:main: STARTED @864ms org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@629f0666
2016-07-02 16:24:55.241:DBUG:oejuc.AbstractLifeCycle:main: starting ProxyConnectionFactory@13b6d03[proxy]
2016-07-02 16:24:55.241:DBUG:oejuc.AbstractLifeCycle:main: STARTED @865ms ProxyConnectionFactory@13b6d03[proxy]
2016-07-02 16:24:55.244:DBUG:oejuc.ContainerLifeCycle:main: ServerConnector@763d9750{proxy,[proxy, http/1.1]}{0.0.0.0:8080} added {acceptor-0@5ebec15,POJO}
2016-07-02 16:24:55.244:DBUG:oejut.QueuedThreadPool:main: queue acceptor-0@5ebec15
2016-07-02 16:24:55.245:DBUG:oejut.QueuedThreadPool:qtp1993134103-17: run acceptor-0@5ebec15
2016-07-02 16:24:55.246:INFO:oejs.AbstractConnector:main: Started ServerConnector@763d9750{proxy,[proxy, http/1.1]}{0.0.0.0:8080}
2016-07-02 16:24:55.246:DBUG:oejuc.AbstractLifeCycle:main: STARTED @870ms ServerConnector@763d9750{proxy,[proxy, http/1.1]}{0.0.0.0:8080}
2016-07-02 16:24:55.246:INFO:oejs.Server:main: Started @870ms
2016-07-02 16:24:55.246:DBUG:oejuc.AbstractLifeCycle:main: STARTED @870ms org.eclipse.jetty.server.Server@5aaa6d82
2016-07-02 16:25:04.046:DBUG:oeji.ManagedSelector:qtp1993134103-17-acceptor-0@5ebec15-ServerConnector@763d9750{proxy,[proxy, http/1.1]}{0.0.0.0:8080}: Queued change org.eclipse.jetty.io.ManagedSelector$Accept@53046486 on org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=0 selected=0
2016-07-02 16:25:04.047:DBUG:oeji.ManagedSelector:qtp1993134103-10: Selector loop woken up from select, 0/0 selected
2016-07-02 16:25:04.048:DBUG:oeji.ManagedSelector:qtp1993134103-10: Running change org.eclipse.jetty.io.ManagedSelector$Accept@53046486
2016-07-02 16:25:04.049:DBUG:oeji.ManagedSelector:qtp1993134103-10: Queued change org.eclipse.jetty.io.ManagedSelector$CreateEndPoint@6f3c7b3b on org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=1 selected=0
2016-07-02 16:25:04.049:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-10: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 produced org.eclipse.jetty.io.ManagedSelector$CreateEndPoint@6f3c7b3b
2016-07-02 16:25:04.049:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-10: EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 dispatch
2016-07-02 16:25:04.049:DBUG:oejut.QueuedThreadPool:qtp1993134103-10: queue EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822
2016-07-02 16:25:04.049:DBUG:oejut.QueuedThreadPool:qtp1993134103-12: run EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822
2016-07-02 16:25:04.049:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-10: EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 run org.eclipse.jetty.io.ManagedSelector$CreateEndPoint@6f3c7b3b
2016-07-02 16:25:04.049:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-12: EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 run
2016-07-02 16:25:04.049:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-12: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 produce enter
2016-07-02 16:25:04.050:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-12: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 producing
2016-07-02 16:25:04.050:DBUG:oeji.ManagedSelector:qtp1993134103-12: Selector loop waiting on select
2016-07-02 16:25:04.060:DBUG:oeji.AbstractEndPoint:qtp1993134103-10: onOpen SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,-,-,6/30000,null@0}{io=0/0,kio=0,kro=0}
2016-07-02 16:25:04.061:DBUG:oeji.IdleTimeout:qtp1993134103-10: SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,-,-,6/30000,null@0}{io=0/0,kio=0,kro=0} idle timeout check, elapsed: 6 ms, remaining: 29994 ms
2016-07-02 16:25:04.067:DBUG:oeji.AbstractConnection:qtp1993134103-10: onOpen ProxyConnection@49376d8e[SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,-,-,12/30000,ProxyConnection@49376d8e}{io=0/0,kio=0,kro=0}]
2016-07-02 16:25:04.068:DBUG:oeji.AbstractConnection:qtp1993134103-10: fillInterested ProxyConnection@49376d8e[SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,-,-,13/30000,ProxyConnection@49376d8e}{io=0/0,kio=0,kro=0}]
2016-07-02 16:25:04.068:DBUG:oeji.FillInterest:qtp1993134103-10: FillInterest@53fa1e89{true,AC.ReadCB@49376d8e{ProxyConnection@49376d8e[SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,0/30000,ProxyConnection@49376d8e}{io=0/0,kio=0,kro=0}]}} register AC.ReadCB@49376d8e{ProxyConnection@49376d8e[SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,1/30000,ProxyConnection@49376d8e}{io=0/0,kio=0,kro=0}]}
2016-07-02 16:25:04.070:DBUG:oeji.FillInterest:qtp1993134103-10: FillInterest@53fa1e89{true,AC.ReadCB@49376d8e{ProxyConnection@49376d8e[SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,2/30000,ProxyConnection@49376d8e}{io=0/0,kio=0,kro=0}]}} register AC.ReadCB@49376d8e{ProxyConnection@49376d8e[SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,3/30000,ProxyConnection@49376d8e}{io=0/0,kio=0,kro=0}]}
2016-07-02 16:25:04.071:DBUG:oeji.SelectChannelEndPoint:qtp1993134103-10: changeInterests p=false 0->1 for SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,3/30000,ProxyConnection@49376d8e}{io=0/1,kio=0,kro=0}
2016-07-02 16:25:04.071:DBUG:oeji.ManagedSelector:qtp1993134103-10: Queued change SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,3/30000,ProxyConnection@49376d8e}{io=0/1,kio=0,kro=0}:runUpdateKey on org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=1 selected=0
2016-07-02 16:25:04.072:DBUG:oeji.ManagedSelector:qtp1993134103-10: Created SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,4/30000,ProxyConnection@49376d8e}{io=0/1,kio=0,kro=0}
2016-07-02 16:25:04.072:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-10: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 ran org.eclipse.jetty.io.ManagedSelector$CreateEndPoint@6f3c7b3b
2016-07-02 16:25:04.072:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-10: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 produce exit
2016-07-02 16:25:04.072:DBUG:oejut.QueuedThreadPool:qtp1993134103-10: ran org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=1 selected=0
2016-07-02 16:25:04.073:DBUG:oeji.ManagedSelector:qtp1993134103-12: Selector loop woken up from select, 0/1 selected
2016-07-02 16:25:04.073:DBUG:oeji.ManagedSelector:qtp1993134103-12: Running change SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,5/30000,ProxyConnection@49376d8e}{io=0/1,kio=0,kro=0}:runUpdateKey
2016-07-02 16:25:04.074:DBUG:oeji.SelectChannelEndPoint:qtp1993134103-12: Key interests updated 0 -> 1 on SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,6/30000,ProxyConnection@49376d8e}{io=1/1,kio=1,kro=0}
2016-07-02 16:25:04.074:DBUG:oeji.ManagedSelector:qtp1993134103-12: Selector loop waiting on select
2016-07-02 16:25:04.074:DBUG:oeji.ManagedSelector:qtp1993134103-12: Selector loop woken up from select, 1/1 selected
2016-07-02 16:25:04.074:DBUG:oeji.SelectChannelEndPoint:qtp1993134103-12: onSelected 1->0 r=true w=false for SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,6/30000,ProxyConnection@49376d8e}{io=1/0,kio=1,kro=1}
2016-07-02 16:25:04.074:DBUG:oeji.SelectChannelEndPoint:qtp1993134103-12: task SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,7/30000,ProxyConnection@49376d8e}{io=1/0,kio=1,kro=1}:runFillable
2016-07-02 16:25:04.075:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-12: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 produced SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,7/30000,ProxyConnection@49376d8e}{io=1/0,kio=1,kro=1}:runFillable
2016-07-02 16:25:04.076:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-12: EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 dispatch
2016-07-02 16:25:04.076:DBUG:oejut.QueuedThreadPool:qtp1993134103-12: queue EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822
2016-07-02 16:25:04.076:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-12: EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 run SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,8/30000,ProxyConnection@49376d8e}{io=1/0,kio=1,kro=1}:runFillable
2016-07-02 16:25:04.076:DBUG:oeji.FillInterest:qtp1993134103-12: FillInterest@53fa1e89{true,AC.ReadCB@49376d8e{ProxyConnection@49376d8e[SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,8/30000,ProxyConnection@49376d8e}{io=1/0,kio=1,kro=1}]}} fillable AC.ReadCB@49376d8e{ProxyConnection@49376d8e[SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,FI,-,8/30000,ProxyConnection@49376d8e}{io=1/0,kio=1,kro=1}]}
2016-07-02 16:25:04.077:DBUG:oejut.QueuedThreadPool:qtp1993134103-13: run EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822
2016-07-02 16:25:04.078:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-13: EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 run
2016-07-02 16:25:04.078:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-13: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 produce enter
2016-07-02 16:25:04.077:DBUG:oeji.ChannelEndPoint:qtp1993134103-12: filled 29 SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,-,-,10/30000,ProxyConnection@49376d8e}{io=1/0,kio=1,kro=1}
2016-07-02 16:25:04.078:WARN:oejs.ProxyConnectionFactory:qtp1993134103-12: Bad character 13 for SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,Open,in,out,-,-,0/30000,ProxyConnection@49376d8e}{io=1/0,kio=1,kro=1}
2016-07-02 16:25:04.078:DBUG:oeji.WriteFlusher:qtp1993134103-12: ignored: WriteFlusher@7943877{IDLE} java.nio.channels.ClosedChannelException
2016-07-02 16:25:04.079:DBUG:oeji.ChannelEndPoint:qtp1993134103-12: close SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,CLOSED,in,out,-,-,1/30000,ProxyConnection@49376d8e}{io=1/0,kio=1,kro=1}
2016-07-02 16:25:04.078:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-13: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 producing
2016-07-02 16:25:04.080:DBUG:oeji.SelectChannelEndPoint:qtp1993134103-13: Key interests updated 1 -> 0 on SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,CLOSED,ISHUT,OSHUT,-,-,2/30000,ProxyConnection@49376d8e}{io=0/0,kio=0,kro=1}
2016-07-02 16:25:04.080:DBUG:oeji.ManagedSelector:qtp1993134103-13: Selector loop waiting on select
2016-07-02 16:25:04.081:DBUG:oeji.ManagedSelector:qtp1993134103-12: Queued change org.eclipse.jetty.io.ManagedSelector$2@486edcce on org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=1 selected=0
2016-07-02 16:25:04.082:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-12: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 ran SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,CLOSED,ISHUT,OSHUT,-,-,4/30000,ProxyConnection@49376d8e}{io=0/0,kio=-1,kro=-1}:runFillable
2016-07-02 16:25:04.082:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-12: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 produce exit
2016-07-02 16:25:04.082:DBUG:oejut.QueuedThreadPool:qtp1993134103-12: ran EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822
2016-07-02 16:25:04.084:DBUG:oeji.ManagedSelector:qtp1993134103-13: Selector loop woken up from select, 0/0 selected
2016-07-02 16:25:04.090:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-13: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 produced org.eclipse.jetty.io.ManagedSelector$2@486edcce
2016-07-02 16:25:04.090:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-13: EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 dispatch
2016-07-02 16:25:04.090:DBUG:oejut.QueuedThreadPool:qtp1993134103-13: queue EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822
2016-07-02 16:25:04.090:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-13: EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 run org.eclipse.jetty.io.ManagedSelector$2@486edcce
2016-07-02 16:25:04.090:DBUG:oeji.ManagedSelector:qtp1993134103-13: Destroyed SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,CLOSED,ISHUT,OSHUT,-,-,12/30000,ProxyConnection@49376d8e}{io=0/0,kio=-1,kro=-1}
2016-07-02 16:25:04.091:DBUG:oeji.AbstractConnection:qtp1993134103-13: onClose ProxyConnection@49376d8e[SelectChannelEndPoint@26ba5622{/127.0.0.1:49883<->8080,CLOSED,ISHUT,OSHUT,-,-,13/30000,ProxyConnection@49376d8e}{io=0/0,kio=-1,kro=-1}]
2016-07-02 16:25:04.091:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-13: EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 ran org.eclipse.jetty.io.ManagedSelector$2@486edcce
2016-07-02 16:25:04.091:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-13: EPC Prod/Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 producing
2016-07-02 16:25:04.091:DBUG:oeji.ManagedSelector:qtp1993134103-13: Selector loop waiting on select
2016-07-02 16:25:04.091:DBUG:oejut.QueuedThreadPool:qtp1993134103-15: run EPC Prod/Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822
2016-07-02 16:25:04.091:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-15: EPC Prod/Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 run
2016-07-02 16:25:04.091:DBUG:oejut.QueuedThreadPool:qtp1993134103-15: ran EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822
^C2016-07-02 16:25:12.294:DBUG:oejuc.AbstractLifeCycle:Thread-0: stopping org.eclipse.jetty.server.Server@5aaa6d82
2016-07-02 16:25:12.294:DBUG:oejs.Server:Thread-0: doStop org.eclipse.jetty.server.Server@5aaa6d82
2016-07-02 16:25:12.295:DBUG:oejut.QueuedThreadPool:qtp1993134103-17: ran qtp1993134103-17-acceptor-0@5ebec15-ServerConnector@763d9750{proxy,[proxy, http/1.1]}{0.0.0.0:8080}
2016-07-02 16:25:12.301:DBUG:oejs.Server:Thread-0: Graceful shutdown org.eclipse.jetty.server.Server@5aaa6d82 by  Sat Jul 02 16:25:17 CEST 2016
2016-07-02 16:25:12.301:DBUG:oejuc.AbstractLifeCycle:Thread-0: stopping ServerConnector@763d9750{proxy,[proxy, http/1.1]}{0.0.0.0:8080}
2016-07-02 16:25:12.301:DBUG:oejuc.AbstractLifeCycle:Thread-0: stopping ProxyConnectionFactory@13b6d03[proxy]
2016-07-02 16:25:12.302:DBUG:oejuc.AbstractLifeCycle:Thread-0: STOPPED ProxyConnectionFactory@13b6d03[proxy]
2016-07-02 16:25:12.302:DBUG:oejuc.AbstractLifeCycle:Thread-0: stopping org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@629f0666
2016-07-02 16:25:12.302:DBUG:oejuc.AbstractLifeCycle:Thread-0: stopping org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=0 selected=0
2016-07-02 16:25:12.302:DBUG:oeji.ManagedSelector:Thread-0: Stopping org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=0 selected=0
2016-07-02 16:25:12.303:DBUG:oeji.ManagedSelector:Thread-0: Queued change org.eclipse.jetty.io.ManagedSelector$CloseEndPoints@6cf8cd6d on org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=0 selected=0
2016-07-02 16:25:12.303:DBUG:oeji.ManagedSelector:qtp1993134103-11: Selector loop woken up from select, 0/0 selected
2016-07-02 16:25:12.303:DBUG:oeji.ManagedSelector:qtp1993134103-11: Running change org.eclipse.jetty.io.ManagedSelector$CloseEndPoints@6cf8cd6d
2016-07-02 16:25:12.303:DBUG:oeji.ManagedSelector:qtp1993134103-11: Closing 0 endPoints on org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=0 selected=0
2016-07-02 16:25:12.303:DBUG:oeji.ManagedSelector:qtp1993134103-11: Closed 0 endPoints on org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=0 selected=0
2016-07-02 16:25:12.303:DBUG:oeji.ManagedSelector:qtp1993134103-11: Selector loop waiting on select
2016-07-02 16:25:12.304:DBUG:oeji.ManagedSelector:Thread-0: Queued change org.eclipse.jetty.io.ManagedSelector$CloseSelector@2d16f31 on org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=0 selected=0
2016-07-02 16:25:12.304:DBUG:oeji.ManagedSelector:qtp1993134103-11: Selector loop woken up from select, 0/0 selected
2016-07-02 16:25:12.304:DBUG:oeji.ManagedSelector:qtp1993134103-11: Running change org.eclipse.jetty.io.ManagedSelector$CloseSelector@2d16f31
2016-07-02 16:25:12.305:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-11: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@4b37ade9 produced null
2016-07-02 16:25:12.305:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-11: EPC Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@4b37ade9 produce exit
2016-07-02 16:25:12.305:DBUG:oeji.ManagedSelector:Thread-0: Stopped org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=-1 selected=-1
2016-07-02 16:25:12.305:DBUG:oejut.QueuedThreadPool:qtp1993134103-11: ran org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=-1 selected=-1
2016-07-02 16:25:12.305:DBUG:oejuc.AbstractLifeCycle:Thread-0: STOPPED org.eclipse.jetty.io.ManagedSelector@6aaa5eb0 id=1 keys=-1 selected=-1
2016-07-02 16:25:12.305:DBUG:oejuc.AbstractLifeCycle:Thread-0: stopping org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=0 selected=0
2016-07-02 16:25:12.305:DBUG:oeji.ManagedSelector:Thread-0: Stopping org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=0 selected=0
2016-07-02 16:25:12.305:DBUG:oeji.ManagedSelector:Thread-0: Queued change org.eclipse.jetty.io.ManagedSelector$CloseEndPoints@286849a3 on org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=0 selected=0
2016-07-02 16:25:12.305:DBUG:oeji.ManagedSelector:qtp1993134103-13: Selector loop woken up from select, 0/0 selected
2016-07-02 16:25:12.305:DBUG:oeji.ManagedSelector:qtp1993134103-13: Running change org.eclipse.jetty.io.ManagedSelector$CloseEndPoints@286849a3
2016-07-02 16:25:12.305:DBUG:oeji.ManagedSelector:qtp1993134103-13: Closing 0 endPoints on org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=0 selected=0
2016-07-02 16:25:12.306:DBUG:oeji.ManagedSelector:qtp1993134103-13: Closed 0 endPoints on org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=0 selected=0
2016-07-02 16:25:12.306:DBUG:oeji.ManagedSelector:qtp1993134103-13: Selector loop waiting on select
2016-07-02 16:25:12.306:DBUG:oeji.ManagedSelector:Thread-0: Queued change org.eclipse.jetty.io.ManagedSelector$CloseSelector@484649f0 on org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=0 selected=0
2016-07-02 16:25:12.306:DBUG:oeji.ManagedSelector:qtp1993134103-13: Selector loop woken up from select, 0/0 selected
2016-07-02 16:25:12.306:DBUG:oeji.ManagedSelector:qtp1993134103-13: Running change org.eclipse.jetty.io.ManagedSelector$CloseSelector@484649f0
2016-07-02 16:25:12.306:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-13: EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 produced null
2016-07-02 16:25:12.306:DBUG:oejuts.ExecuteProduceConsume:qtp1993134103-13: EPC Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822 produce exit
2016-07-02 16:25:12.306:DBUG:oejut.QueuedThreadPool:qtp1993134103-13: ran EPC Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@40e96822
2016-07-02 16:25:12.306:DBUG:oeji.ManagedSelector:Thread-0: Stopped org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=-1 selected=-1
2016-07-02 16:25:12.306:DBUG:oejuc.AbstractLifeCycle:Thread-0: STOPPED org.eclipse.jetty.io.ManagedSelector@13969fbe id=0 keys=-1 selected=-1
2016-07-02 16:25:12.307:DBUG:oejuc.AbstractLifeCycle:Thread-0: STOPPED org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@629f0666
2016-07-02 16:25:12.307:DBUG:oejuc.AbstractLifeCycle:Thread-0: stopping HttpConnectionFactory@33e5ccce[HTTP/1.1]
2016-07-02 16:25:12.307:DBUG:oejuc.AbstractLifeCycle:Thread-0: STOPPED HttpConnectionFactory@33e5ccce[HTTP/1.1]
2016-07-02 16:25:12.307:INFO:oejs.AbstractConnector:Thread-0: Stopped ServerConnector@763d9750{proxy,[proxy, http/1.1]}{0.0.0.0:8080}
2016-07-02 16:25:12.307:DBUG:oejuc.AbstractLifeCycle:Thread-0: STOPPED ServerConnector@763d9750{proxy,[proxy, http/1.1]}{0.0.0.0:8080}
2016-07-02 16:25:12.307:DBUG:oejsh.AbstractHandler:Thread-0: stopping org.eclipse.jetty.server.Server@5aaa6d82
2016-07-02 16:25:12.307:DBUG:oejuc.AbstractLifeCycle:Thread-0: stopping org.eclipse.jetty.server.handler.HandlerCollection@1996cd68[org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[], org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4]
2016-07-02 16:25:12.308:DBUG:oejsh.AbstractHandler:Thread-0: stopping org.eclipse.jetty.server.handler.HandlerCollection@1996cd68[org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[], org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4]
2016-07-02 16:25:12.308:DBUG:oejuc.AbstractLifeCycle:Thread-0: stopping org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4
2016-07-02 16:25:12.308:DBUG:oejsh.AbstractHandler:Thread-0: stopping org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4
2016-07-02 16:25:12.308:DBUG:oejuc.AbstractLifeCycle:Thread-0: STOPPED org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4
2016-07-02 16:25:12.308:DBUG:oejuc.AbstractLifeCycle:Thread-0: stopping org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[]
2016-07-02 16:25:12.308:DBUG:oejsh.AbstractHandler:Thread-0: stopping org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[]
2016-07-02 16:25:12.308:DBUG:oejuc.AbstractLifeCycle:Thread-0: STOPPED org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[]
2016-07-02 16:25:12.308:DBUG:oejuc.AbstractLifeCycle:Thread-0: STOPPED org.eclipse.jetty.server.handler.HandlerCollection@1996cd68[org.eclipse.jetty.server.handler.ContextHandlerCollection@6d1e7682[], org.eclipse.jetty.server.handler.DefaultHandler@424c0bc4]
2016-07-02 16:25:12.308:DBUG:oejuc.AbstractLifeCycle:Thread-0: stopping org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@2f333739
2016-07-02 16:25:12.308:DBUG:oejuc.AbstractLifeCycle:Thread-0: STOPPED org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@2f333739
2016-07-02 16:25:12.308:DBUG:oejuc.AbstractLifeCycle:Thread-0: stopping qtp1993134103{STARTED,10<=10<=200,i=10,q=0}
2016-07-02 16:25:12.311:DBUG:oejuc.AbstractLifeCycle:Thread-0: STOPPED qtp1993134103{STOPPED,10<=10<=200,i=0,q=0}
2016-07-02 16:25:12.311:DBUG:oejut.ShutdownThread:Thread-0: shutdown already commenced
2016-07-02 16:25:12.311:DBUG:oejuc.AbstractLifeCycle:Thread-0: STOPPED org.eclipse.jetty.server.Server@5aaa6d82
2016-07-02 16:25:12.311:DBUG:oejut.ShutdownThread:Thread-0: Stopped org.eclipse.jetty.server.Server@5aaa6d82
2016-07-02 16:25:12.312:DBUG:oejut.ShutdownThread:Thread-0: Destroyed org.eclipse.jetty.server.Server@5aaa6d82

Back to the top