Skip to content

[tomcat] Spring boot web always return 400 when use a domain name #13236

New issue
New issue
Closed
Closed
[tomcat] Spring boot web always return 400 when use a domain name#13236
Labels
for: external-projectFor an external project and not something we can fix
@ghost

Description

@ghost
ghost
opened on May 23, 2018
DESKTOP-J5RVINV ~ # curl -v -i 127.0.0.1:8080 -H "Host: domain.sfm"
* Rebuilt URL to: 127.0.0.1:8080/
*   Trying 127.0.0.1...
* TCP_NODELAY set
* Connected to 127.0.0.1 (127.0.0.1) port 8080 (#0)
> GET / HTTP/1.1
> Host: domain.sfm
> User-Agent: curl/7.52.1
> Accept: */*
>
< HTTP/1.1 200
HTTP/1.1 200
< Content-Type: text/plain;charset=UTF-8
Content-Type: text/plain;charset=UTF-8
< Content-Length: 11
Content-Length: 11
< Date: Wed, 23 May 2018 00:08:51 GMT
Date: Wed, 23 May 2018 00:08:51 GMT

<
* Curl_http_done: called premature == 0
* Connection #0 to host 127.0.0.1 left intact
Hello world#                                                               
DESKTOP-J5RVINV ~ # curl -v -i 127.0.0.1:8080 -H "Host: domain.sf1m"
* Rebuilt URL to: 127.0.0.1:8080/
*   Trying 127.0.0.1...
* TCP_NODELAY set
* Connected to 127.0.0.1 (127.0.0.1) port 8080 (#0)
> GET / HTTP/1.1
> Host: domain.sf1m
> User-Agent: curl/7.52.1
> Accept: */*
>
< HTTP/1.1 400
HTTP/1.1 400
< Transfer-Encoding: chunked
Transfer-Encoding: chunked
< Date: Wed, 23 May 2018 00:08:55 GMT
Date: Wed, 23 May 2018 00:08:55 GMT
< Connection: close
Connection: close

<
* Curl_http_done: called premature == 0
* Closing connection 0

If the domain .ext contains number, spring boot web always return 400 error code.

My source code

DemoApplication.java

package com.example.demo;

import org.springframework.boot.SpringApplication;
import org.springframework.boot.autoconfigure.SpringBootApplication;

@SpringBootApplication
public class DemoApplication {

    public static void main(String[] args) {
        SpringApplication.run(DemoApplication.class, args);
    }
}

HomeController.java

package com.example.demo;

import org.springframework.web.bind.annotation.GetMapping;
import org.springframework.web.bind.annotation.RestController;

@RestController
public class HomeController {
    @GetMapping("/")
    public String home () {
        return "Hello world";
    }
}

Activity

spring-projects-issues
added
status: waiting-for-triageAn issue we've not yet triaged
on May 23, 2018
ghost

ghost commented on May 23, 2018

@ghost
ghost
on May 23, 2018

The debug log


2018-05-23 08:12:34.703 DEBUG 20796 --- [8080-Acceptor-0] o.apache.tomcat.util.threads.LimitLatch  : Counting up[http-nio-8080-Acceptor-0] latch=1
2018-05-23 08:12:34.740 DEBUG 20796 --- [nio-8080-exec-9] org.apache.tomcat.util.modeler.Registry  : Managed= Tomcat:type=RequestProcessor,worker="http-nio-8080",name=HttpRequest1
2018-05-23 08:12:34.740 DEBUG 20796 --- [nio-8080-exec-9] org.apache.tomcat.util.modeler.Registry  : Looking for descriptor 
2018-05-23 08:12:34.740 DEBUG 20796 --- [nio-8080-exec-9] org.apache.tomcat.util.modeler.Registry  : Introspecting 
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute virtualHost public java.lang.String org.apache.coyote.RequestInfo.getVirtualHost() null
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute bytesSent public long org.apache.coyote.RequestInfo.getBytesSent() public void org.apache.coyote.RequestInfo.setBytesSent(long)
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute method public java.lang.String org.apache.coyote.RequestInfo.getMethod() null
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute remoteAddr public java.lang.String org.apache.coyote.RequestInfo.getRemoteAddr() null
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute requestBytesSent public long org.apache.coyote.RequestInfo.getRequestBytesSent() null
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute rpName public javax.management.ObjectName org.apache.coyote.RequestInfo.getRpName() public void org.apache.coyote.RequestInfo.setRpName(javax.management.ObjectName)
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute contentLength public int org.apache.coyote.RequestInfo.getContentLength() null
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute workerThreadName public java.lang.String org.apache.coyote.RequestInfo.getWorkerThreadName() public void org.apache.coyote.RequestInfo.setWorkerThreadName(java.lang.String)
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute bytesReceived public long org.apache.coyote.RequestInfo.getBytesReceived() public void org.apache.coyote.RequestInfo.setBytesReceived(long)
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute requestProcessingTime public long org.apache.coyote.RequestInfo.getRequestProcessingTime() null
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute globalProcessor public org.apache.coyote.RequestGroupInfo org.apache.coyote.RequestInfo.getGlobalProcessor() public void org.apache.coyote.RequestInfo.setGlobalProcessor(org.apache.coyote.RequestGroupInfo)
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute remoteAddrForwarded public java.lang.String org.apache.coyote.RequestInfo.getRemoteAddrForwarded() null
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute protocol public java.lang.String org.apache.coyote.RequestInfo.getProtocol() null
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute currentQueryString public java.lang.String org.apache.coyote.RequestInfo.getCurrentQueryString() null
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute maxRequestUri public java.lang.String org.apache.coyote.RequestInfo.getMaxRequestUri() public void org.apache.coyote.RequestInfo.setMaxRequestUri(java.lang.String)
2018-05-23 08:12:34.741 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute requestBytesReceived public long org.apache.coyote.RequestInfo.getRequestBytesReceived() null
2018-05-23 08:12:34.744 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute serverPort public int org.apache.coyote.RequestInfo.getServerPort() null
2018-05-23 08:12:34.744 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute stage public int org.apache.coyote.RequestInfo.getStage() public void org.apache.coyote.RequestInfo.setStage(int)
2018-05-23 08:12:34.744 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute requestCount public int org.apache.coyote.RequestInfo.getRequestCount() public void org.apache.coyote.RequestInfo.setRequestCount(int)
2018-05-23 08:12:34.744 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute maxTime public long org.apache.coyote.RequestInfo.getMaxTime() public void org.apache.coyote.RequestInfo.setMaxTime(long)
2018-05-23 08:12:34.744 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute currentUri public java.lang.String org.apache.coyote.RequestInfo.getCurrentUri() null
2018-05-23 08:12:34.744 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute processingTime public long org.apache.coyote.RequestInfo.getProcessingTime() public void org.apache.coyote.RequestInfo.setProcessingTime(long)
2018-05-23 08:12:34.744 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute lastRequestProcessingTime public long org.apache.coyote.RequestInfo.getLastRequestProcessingTime() public void org.apache.coyote.RequestInfo.setLastRequestProcessingTime(long)
2018-05-23 08:12:34.744 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Introspected attribute errorCount public int org.apache.coyote.RequestInfo.getErrorCount() public void org.apache.coyote.RequestInfo.setErrorCount(int)
2018-05-23 08:12:34.744 DEBUG 20796 --- [nio-8080-exec-9] m.m.MbeansDescriptorsIntrospectionSource : Setting name: org.apache.coyote.RequestInfo
2018-05-23 08:12:34.744 DEBUG 20796 --- [nio-8080-exec-9] o.a.tomcat.util.modeler.BaseModelMBean   : preRegister org.apache.coyote.RequestInfo@26d260d3 Tomcat:type=RequestProcessor,worker="http-nio-8080",name=HttpRequest1
2018-05-23 08:12:34.745 DEBUG 20796 --- [nio-8080-exec-9] o.a.tomcat.util.net.SocketWrapperBase    : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@127a42c7:org.apache.tomcat.util.net.NioChannel@fd4be3b:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:63434]], Read from buffer: [0]
2018-05-23 08:12:34.745 DEBUG 20796 --- [nio-8080-exec-9] org.apache.tomcat.util.net.NioEndpoint   : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@127a42c7:org.apache.tomcat.util.net.NioChannel@fd4be3b:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:63434]], Read direct from socket: [75]
2018-05-23 08:12:34.746 DEBUG 20796 --- [nio-8080-exec-9] o.a.coyote.http11.Http11InputBuffer      : Received [GET / HTTP/1.1
Host: domain.sf1m
User-Agent: curl/7.52.1
Accept: */*

]
2018-05-23 08:12:34.758 DEBUG 20796 --- [nio-8080-exec-9] org.apache.tomcat.util.http.Parameters   : Set query string encoding to UTF-8
2018-05-23 08:12:34.764 DEBUG 20796 --- [nio-8080-exec-9] o.apache.coyote.http11.Http11Processor   : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@127a42c7:org.apache.tomcat.util.net.NioChannel@fd4be3b:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:63434]], Status in: [OPEN_READ], State out: [CLOSED]
2018-05-23 08:12:34.765 DEBUG 20796 --- [nio-8080-exec-9] o.apache.tomcat.util.threads.LimitLatch  : Counting down[http-nio-8080-exec-9] latch=1
2018-05-23 08:12:34.766 DEBUG 20796 --- [nio-8080-exec-9] org.apache.tomcat.util.net.NioEndpoint   : Socket: [org.apache.tomcat.util.net.NioChannel@fd4be3b:java.nio.channels.SocketChannel[closed]] closed
ghost

ghost commented on May 23, 2018

@ghost
ghost
on May 23, 2018
2018-05-23 08:13:45.546 DEBUG 20796 --- [8080-Acceptor-0] o.apache.tomcat.util.threads.LimitLatch  : Counting up[http-nio-8080-Acceptor-0] latch=1
2018-05-23 08:13:45.547 DEBUG 20796 --- [nio-8080-exec-8] o.a.tomcat.util.net.SocketWrapperBase    : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@19bb7d3c:org.apache.tomcat.util.net.NioChannel@fd4be3b:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:63456]], Read from buffer: [0]
2018-05-23 08:13:45.547 DEBUG 20796 --- [nio-8080-exec-8] org.apache.tomcat.util.net.NioEndpoint   : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@19bb7d3c:org.apache.tomcat.util.net.NioChannel@fd4be3b:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:63456]], Read direct from socket: [74]
2018-05-23 08:13:45.547 DEBUG 20796 --- [nio-8080-exec-8] o.a.coyote.http11.Http11InputBuffer      : Received [GET / HTTP/1.1
Host: domain.sfm
User-Agent: curl/7.52.1
Accept: */*

]
2018-05-23 08:13:45.553 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.authenticator.AuthenticatorBase    : Security checking request GET /
2018-05-23 08:13:45.553 DEBUG 20796 --- [nio-8080-exec-8] org.apache.catalina.realm.RealmBase      :   No applicable constraints defined
2018-05-23 08:13:45.563 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.a.jaspic.AuthConfigFactoryImpl     : Loading persistent provider registrations from [C:\Users\huzhifeng\AppData\Local\Temp\tomcat.8735605562742162168.8080\conf\jaspic-providers.xml]
2018-05-23 08:13:45.563 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.authenticator.AuthenticatorBase    :  Not subject to any constraint
2018-05-23 08:13:45.563 DEBUG 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : Initializing servlet 'dispatcherServlet'
2018-05-23 08:13:45.564  INFO 20796 --- [nio-8080-exec-8] o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring FrameworkServlet 'dispatcherServlet'
2018-05-23 08:13:45.564  INFO 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : FrameworkServlet 'dispatcherServlet': initialization started
2018-05-23 08:13:45.564 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'multipartResolver'
2018-05-23 08:13:45.564 DEBUG 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : Using MultipartResolver [org.springframework.web.multipart.support.StandardServletMultipartResolver@5cfba2e]
2018-05-23 08:13:45.565 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'
2018-05-23 08:13:45.567 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(java.lang.ObjectCustomizer)
2018-05-23 08:13:45.569 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.570 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolverCustomizer)
2018-05-23 08:13:45.570 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.571 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(org.springframework.web.servlet.LocaleResolverCustomizer)
2018-05-23 08:13:45.572 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.573 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Finished creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'
2018-05-23 08:13:45.573 DEBUG 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : Unable to locate LocaleResolver with name 'localeResolver': using default [org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver@11340e36]
2018-05-23 08:13:45.573 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'
2018-05-23 08:13:45.574 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(java.lang.ObjectCustomizer)
2018-05-23 08:13:45.574 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.575 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(org.springframework.web.servlet.theme.AbstractThemeResolverCustomizer)
2018-05-23 08:13:45.575 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.576 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(org.springframework.web.servlet.theme.FixedThemeResolverCustomizer)
2018-05-23 08:13:45.576 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.577 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(org.springframework.web.servlet.ThemeResolverCustomizer)
2018-05-23 08:13:45.578 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.579 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Finished creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'
2018-05-23 08:13:45.579 DEBUG 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : Unable to locate ThemeResolver with name 'themeResolver': using default [org.springframework.web.servlet.theme.FixedThemeResolver@6f4a5167]
2018-05-23 08:13:45.579 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'faviconHandlerMapping'
2018-05-23 08:13:45.579 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'requestMappingHandlerMapping'
2018-05-23 08:13:45.579 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'viewControllerHandlerMapping'
2018-05-23 08:13:45.579 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'beanNameHandlerMapping'
2018-05-23 08:13:45.579 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'resourceHandlerMapping'
2018-05-23 08:13:45.579 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'defaultServletHandlerMapping'
2018-05-23 08:13:45.579 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'welcomePageHandlerMapping'
2018-05-23 08:13:45.580 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'requestMappingHandlerAdapter'
2018-05-23 08:13:45.580 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'httpRequestHandlerAdapter'
2018-05-23 08:13:45.580 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'simpleControllerHandlerAdapter'
2018-05-23 08:13:45.580 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'errorAttributes'
2018-05-23 08:13:45.580 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'handlerExceptionResolver'
2018-05-23 08:13:45.581 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'
2018-05-23 08:13:45.582 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(java.lang.ObjectCustomizer)
2018-05-23 08:13:45.582 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.582 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(org.springframework.web.servlet.view.DefaultRequestToViewNameTranslatorCustomizer)
2018-05-23 08:13:45.585 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.586 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(org.springframework.web.servlet.RequestToViewNameTranslatorCustomizer)
2018-05-23 08:13:45.587 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.587 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Finished creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'
2018-05-23 08:13:45.587 DEBUG 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : Unable to locate RequestToViewNameTranslator with name 'viewNameTranslator': using default [org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator@556e0494]
2018-05-23 08:13:45.587 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'beanNameViewResolver'
2018-05-23 08:13:45.587 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'mvcViewResolver'
2018-05-23 08:13:45.589 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'defaultViewResolver'
2018-05-23 08:13:45.589 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'viewResolver'
2018-05-23 08:13:45.590 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Creating instance of bean 'org.springframework.web.servlet.support.SessionFlashMapManager'
2018-05-23 08:13:45.591 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(java.lang.ObjectCustomizer)
2018-05-23 08:13:45.593 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.595 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(org.springframework.web.servlet.support.AbstractFlashMapManagerCustomizer)
2018-05-23 08:13:45.596 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.597 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(org.springframework.web.servlet.support.SessionFlashMapManagerCustomizer)
2018-05-23 08:13:45.598 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.599 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     findClass(org.springframework.web.servlet.FlashMapManagerCustomizer)
2018-05-23 08:13:45.600 DEBUG 20796 --- [nio-8080-exec-8] o.a.c.loader.WebappClassLoaderBase       :     --> Returning ClassNotFoundException
2018-05-23 08:13:45.602 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Finished creating instance of bean 'org.springframework.web.servlet.support.SessionFlashMapManager'
2018-05-23 08:13:45.602 DEBUG 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : Unable to locate FlashMapManager with name 'flashMapManager': using default [org.springframework.web.servlet.support.SessionFlashMapManager@730ef004]
2018-05-23 08:13:45.602 DEBUG 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : Published WebApplicationContext of servlet 'dispatcherServlet' as ServletContext attribute with name [org.springframework.web.servlet.FrameworkServlet.CONTEXT.dispatcherServlet]
2018-05-23 08:13:45.602  INFO 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : FrameworkServlet 'dispatcherServlet': initialization completed in 38 ms
2018-05-23 08:13:45.602 DEBUG 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : Servlet 'dispatcherServlet' configured successfully
2018-05-23 08:13:45.614 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.w.s.f.OrderedRequestContextFilter  : Bound request context to thread: org.apache.catalina.connector.RequestFacade@59372aac
2018-05-23 08:13:45.615 DEBUG 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : DispatcherServlet with name 'dispatcherServlet' processing GET request for [/]
2018-05-23 08:13:45.619 DEBUG 20796 --- [nio-8080-exec-8] s.w.s.m.m.a.RequestMappingHandlerMapping : Looking up handler method for path /
2018-05-23 08:13:45.621 DEBUG 20796 --- [nio-8080-exec-8] s.w.s.m.m.a.RequestMappingHandlerMapping : Returning handler method [public java.lang.String com.example.demo.HomeController.home()]
2018-05-23 08:13:45.621 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'homeController'
2018-05-23 08:13:45.622 DEBUG 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : Last-Modified value for [/] is: -1
2018-05-23 08:13:45.648 DEBUG 20796 --- [nio-8080-exec-8] m.m.a.RequestResponseBodyMethodProcessor : Written [Hello world] as "text/plain" using [org.springframework.http.converter.StringHttpMessageConverter@37b03376]
2018-05-23 08:13:45.649 DEBUG 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : Null ModelAndView returned to DispatcherServlet with name 'dispatcherServlet': assuming HandlerAdapter completed request handling
2018-05-23 08:13:45.649 DEBUG 20796 --- [nio-8080-exec-8] o.s.web.servlet.DispatcherServlet        : Successfully completed request
2018-05-23 08:13:45.651 DEBUG 20796 --- [nio-8080-exec-8] o.s.b.w.s.f.OrderedRequestContextFilter  : Cleared thread-bound request context: org.apache.catalina.connector.RequestFacade@59372aac
2018-05-23 08:13:45.651 DEBUG 20796 --- [nio-8080-exec-8] o.a.tomcat.util.net.SocketWrapperBase    : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@19bb7d3c:org.apache.tomcat.util.net.NioChannel@fd4be3b:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:63456]], Read from buffer: [0]
2018-05-23 08:13:45.651 DEBUG 20796 --- [nio-8080-exec-8] org.apache.tomcat.util.net.NioEndpoint   : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@19bb7d3c:org.apache.tomcat.util.net.NioChannel@fd4be3b:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:63456]], Read direct from socket: [0]
2018-05-23 08:13:45.651 DEBUG 20796 --- [nio-8080-exec-8] o.apache.coyote.http11.Http11Processor   : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@19bb7d3c:org.apache.tomcat.util.net.NioChannel@fd4be3b:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:63456]], Status in: [OPEN_READ], State out: [OPEN]
2018-05-23 08:13:45.669 DEBUG 20796 --- [nio-8080-exec-7] o.a.tomcat.util.net.SocketWrapperBase    : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@19bb7d3c:org.apache.tomcat.util.net.NioChannel@fd4be3b:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:63456]], Read from buffer: [0]
2018-05-23 08:13:45.669 DEBUG 20796 --- [nio-8080-exec-7] o.apache.coyote.http11.Http11Processor   : Error parsing HTTP request header

java.io.EOFException: null
	at org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper.fillReadBuffer(NioEndpoint.java:1259) ~[tomcat-embed-core-8.5.31.jar:8.5.31]
	at org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper.read(NioEndpoint.java:1193) ~[tomcat-embed-core-8.5.31.jar:8.5.31]
	at org.apache.coyote.http11.Http11InputBuffer.fill(Http11InputBuffer.java:725) ~[tomcat-embed-core-8.5.31.jar:8.5.31]
	at org.apache.coyote.http11.Http11InputBuffer.parseRequestLine(Http11InputBuffer.java:368) ~[tomcat-embed-core-8.5.31.jar:8.5.31]
	at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:687) ~[tomcat-embed-core-8.5.31.jar:8.5.31]
	at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66) [tomcat-embed-core-8.5.31.jar:8.5.31]
	at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:790) [tomcat-embed-core-8.5.31.jar:8.5.31]
	at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1468) [tomcat-embed-core-8.5.31.jar:8.5.31]
	at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49) [tomcat-embed-core-8.5.31.jar:8.5.31]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_172]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_172]
	at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) [tomcat-embed-core-8.5.31.jar:8.5.31]
	at java.lang.Thread.run(Thread.java:748) [na:1.8.0_172]

2018-05-23 08:13:45.669 DEBUG 20796 --- [nio-8080-exec-7] o.apache.coyote.http11.Http11Processor   : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@19bb7d3c:org.apache.tomcat.util.net.NioChannel@fd4be3b:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:63456]], Status in: [OPEN_READ], State out: [CLOSED]
2018-05-23 08:13:45.670 DEBUG 20796 --- [nio-8080-exec-7] o.apache.tomcat.util.threads.LimitLatch  : Counting down[http-nio-8080-exec-7] latch=1
2018-05-23 08:13:45.670 DEBUG 20796 --- [nio-8080-exec-7] org.apache.tomcat.util.net.NioEndpoint   : Socket: [org.apache.tomcat.util.net.NioChannel@fd4be3b:java.nio.channels.SocketChannel[closed]] closed
ghost

ghost commented on May 23, 2018

@ghost
ghost
on May 23, 2018 · edited by philwebb

Seems the problem belong to tomcat, if we switch to jetty, all things working well

DESKTOP-J5RVINV ~ # curl -v -i 127.0.0.1:8080 -H "Host: domain.sf1m"
* Rebuilt URL to: 127.0.0.1:8080/
*   Trying 127.0.0.1...
* TCP_NODELAY set
* Connected to 127.0.0.1 (127.0.0.1) port 8080 (#0)
> GET / HTTP/1.1
> Host: domain.sf1m
> User-Agent: curl/7.52.1
> Accept: */*
>
< HTTP/1.1 200 OK
HTTP/1.1 200 OK
< Date: Wed, 23 May 2018 00:17:29 GMT
Date: Wed, 23 May 2018 00:17:29 GMT
< Content-Type: text/plain;charset=utf-8
Content-Type: text/plain;charset=utf-8
< Content-Length: 11
Content-Length: 11

<
* Curl_http_done: called premature == 0
* Connection #0 to host 127.0.0.1 left intact
Hello world#                                                                                                          DESKTOP-J5RVINV ~ # curl -v -i 127.0.0.1:8080 -H "Host: domain.sfm"
* Rebuilt URL to: 127.0.0.1:8080/
*   Trying 127.0.0.1...
* TCP_NODELAY set
* Connected to 127.0.0.1 (127.0.0.1) port 8080 (#0)
> GET / HTTP/1.1
> Host: domain.sfm
> User-Agent: curl/7.52.1
> Accept: */*
>
< HTTP/1.1 200 OK
HTTP/1.1 200 OK
< Date: Wed, 23 May 2018 00:17:35 GMT
Date: Wed, 23 May 2018 00:17:35 GMT
< Content-Type: text/plain;charset=utf-8
Content-Type: text/plain;charset=utf-8
< Content-Length: 11
Content-Length: 11

<
* Curl_http_done: called premature == 0
* Connection #0 to host 127.0.0.1 left intact
Hello world#

And the log

2018-05-23 08:17:22.351 DEBUG 17572 --- [           main] o.s.w.s.resource.ResourceUrlProvider     : Looking for resource handler mappings
2018-05-23 08:17:22.352 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'faviconHandlerMapping'
2018-05-23 08:17:22.352 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'resourceHandlerMapping'
2018-05-23 08:17:22.353 DEBUG 17572 --- [           main] o.s.w.s.resource.ResourceUrlProvider     : Found resource handler mapping: URL pattern="/**/favicon.ico", locations=[class path resource [META-INF/resources/], class path resource [resources/], class path resource [static/], class path resource [public/], ServletContext resource [/], class path resource []], resolvers=[org.springframework.web.servlet.resource.PathResourceResolver@6363b3b7]
2018-05-23 08:17:22.353 DEBUG 17572 --- [           main] o.s.w.s.resource.ResourceUrlProvider     : Found resource handler mapping: URL pattern="/webjars/**", locations=[class path resource [META-INF/resources/webjars/]], resolvers=[org.springframework.web.servlet.resource.PathResourceResolver@523d17f3]
2018-05-23 08:17:22.353 DEBUG 17572 --- [           main] o.s.w.s.resource.ResourceUrlProvider     : Found resource handler mapping: URL pattern="/**", locations=[class path resource [META-INF/resources/], class path resource [resources/], class path resource [static/], class path resource [public/], ServletContext resource [/]], resolvers=[org.springframework.web.servlet.resource.PathResourceResolver@580a097]
2018-05-23 08:17:22.354 DEBUG 17572 --- [           main] o.s.c.e.PropertySourcesPropertyResolver  : Could not find key 'spring.liveBeansView.mbeanDomain' in any property source
2018-05-23 08:17:22.354 DEBUG 17572 --- [           main] o.e.j.util.component.ContainerLifeCycle  : org.eclipse.jetty.server.Server@5471204c[9.4.10.v20180503] added {ServerConnector@4e2c22ff{HTTP/1.1,[http/1.1]}{0.0.0.0:8080},UNMANAGED}
2018-05-23 08:17:22.355 DEBUG 17572 --- [           main] org.eclipse.jetty.servlet.FilterHolder   : Filter.init WebSocketUpgradeFilter[configuration=org.eclipse.jetty.websocket.server.NativeWebSocketConfiguration@47ef0ade]
2018-05-23 08:17:22.355 DEBUG 17572 --- [           main] org.eclipse.jetty.servlet.FilterHolder   : Filter.init org.springframework.boot.web.servlet.filter.OrderedCharacterEncodingFilter@1f6e5891
2018-05-23 08:17:22.355 DEBUG 17572 --- [           main] s.b.w.s.f.OrderedCharacterEncodingFilter : Initializing filter 'characterEncodingFilter'
2018-05-23 08:17:22.357 DEBUG 17572 --- [           main] s.b.w.s.f.OrderedCharacterEncodingFilter : Filter 'characterEncodingFilter' configured successfully
2018-05-23 08:17:22.357 DEBUG 17572 --- [           main] org.eclipse.jetty.servlet.FilterHolder   : Filter.init org.springframework.boot.web.servlet.filter.OrderedHiddenHttpMethodFilter@4210620e
2018-05-23 08:17:22.357 DEBUG 17572 --- [           main] .s.b.w.s.f.OrderedHiddenHttpMethodFilter : Initializing filter 'hiddenHttpMethodFilter'
2018-05-23 08:17:22.357 DEBUG 17572 --- [           main] .s.b.w.s.f.OrderedHiddenHttpMethodFilter : Filter 'hiddenHttpMethodFilter' configured successfully
2018-05-23 08:17:22.357 DEBUG 17572 --- [           main] org.eclipse.jetty.servlet.FilterHolder   : Filter.init org.springframework.boot.web.servlet.filter.OrderedHttpPutFormContentFilter@701b2eb3
2018-05-23 08:17:22.357 DEBUG 17572 --- [           main] .b.w.s.f.OrderedHttpPutFormContentFilter : Initializing filter 'httpPutFormContentFilter'
2018-05-23 08:17:22.357 DEBUG 17572 --- [           main] .b.w.s.f.OrderedHttpPutFormContentFilter : Filter 'httpPutFormContentFilter' configured successfully
2018-05-23 08:17:22.357 DEBUG 17572 --- [           main] org.eclipse.jetty.servlet.FilterHolder   : Filter.init org.springframework.boot.web.servlet.filter.OrderedRequestContextFilter@16ae7ab1
2018-05-23 08:17:22.357 DEBUG 17572 --- [           main] o.s.b.w.s.f.OrderedRequestContextFilter  : Initializing filter 'requestContextFilter'
2018-05-23 08:17:22.357 DEBUG 17572 --- [           main] o.s.b.w.s.f.OrderedRequestContextFilter  : Filter 'requestContextFilter' configured successfully
2018-05-23 08:17:22.359 DEBUG 17572 --- [           main] org.eclipse.jetty.servlet.ServletHolder  : Servlet.init org.springframework.web.servlet.DispatcherServlet@61965ee2 for dispatcherServlet
2018-05-23 08:17:22.359 DEBUG 17572 --- [           main] o.s.web.servlet.DispatcherServlet        : Initializing servlet 'dispatcherServlet'
2018-05-23 08:17:22.360  INFO 17572 --- [           main] o.e.j.s.h.ContextHandler.application     : Initializing Spring FrameworkServlet 'dispatcherServlet'
2018-05-23 08:17:22.360  INFO 17572 --- [           main] o.s.web.servlet.DispatcherServlet        : FrameworkServlet 'dispatcherServlet': initialization started
2018-05-23 08:17:22.360 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'multipartResolver'
2018-05-23 08:17:22.360 DEBUG 17572 --- [           main] o.s.web.servlet.DispatcherServlet        : Using MultipartResolver [org.springframework.web.multipart.support.StandardServletMultipartResolver@73262b52]
2018-05-23 08:17:22.361 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'
2018-05-23 08:17:22.362 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Finished creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'
2018-05-23 08:17:22.362 DEBUG 17572 --- [           main] o.s.web.servlet.DispatcherServlet        : Unable to locate LocaleResolver with name 'localeResolver': using default [org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver@a4a00f5]
2018-05-23 08:17:22.363 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'
2018-05-23 08:17:22.365 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Finished creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'
2018-05-23 08:17:22.365 DEBUG 17572 --- [           main] o.s.web.servlet.DispatcherServlet        : Unable to locate ThemeResolver with name 'themeResolver': using default [org.springframework.web.servlet.theme.FixedThemeResolver@2a0545ef]
2018-05-23 08:17:22.365 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'faviconHandlerMapping'
2018-05-23 08:17:22.365 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'requestMappingHandlerMapping'
2018-05-23 08:17:22.365 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'viewControllerHandlerMapping'
2018-05-23 08:17:22.365 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'beanNameHandlerMapping'
2018-05-23 08:17:22.365 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'resourceHandlerMapping'
2018-05-23 08:17:22.365 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'defaultServletHandlerMapping'
2018-05-23 08:17:22.365 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'welcomePageHandlerMapping'
2018-05-23 08:17:22.366 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'requestMappingHandlerAdapter'
2018-05-23 08:17:22.366 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'httpRequestHandlerAdapter'
2018-05-23 08:17:22.366 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'simpleControllerHandlerAdapter'
2018-05-23 08:17:22.366 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'errorAttributes'
2018-05-23 08:17:22.366 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'handlerExceptionResolver'
2018-05-23 08:17:22.367 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'
2018-05-23 08:17:22.369 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Finished creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'
2018-05-23 08:17:22.369 DEBUG 17572 --- [           main] o.s.web.servlet.DispatcherServlet        : Unable to locate RequestToViewNameTranslator with name 'viewNameTranslator': using default [org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator@40b55665]
2018-05-23 08:17:22.370 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'beanNameViewResolver'
2018-05-23 08:17:22.370 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'mvcViewResolver'
2018-05-23 08:17:22.370 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'defaultViewResolver'
2018-05-23 08:17:22.370 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'viewResolver'
2018-05-23 08:17:22.371 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Creating instance of bean 'org.springframework.web.servlet.support.SessionFlashMapManager'
2018-05-23 08:17:22.373 DEBUG 17572 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Finished creating instance of bean 'org.springframework.web.servlet.support.SessionFlashMapManager'
2018-05-23 08:17:22.373 DEBUG 17572 --- [           main] o.s.web.servlet.DispatcherServlet        : Unable to locate FlashMapManager with name 'flashMapManager': using default [org.springframework.web.servlet.support.SessionFlashMapManager@2660dcfc]
2018-05-23 08:17:22.374 DEBUG 17572 --- [           main] o.s.web.servlet.DispatcherServlet        : Published WebApplicationContext of servlet 'dispatcherServlet' as ServletContext attribute with name [org.springframework.web.servlet.FrameworkServlet.CONTEXT.dispatcherServlet]
2018-05-23 08:17:22.374  INFO 17572 --- [           main] o.s.web.servlet.DispatcherServlet        : FrameworkServlet 'dispatcherServlet': initialization completed in 14 ms
2018-05-23 08:17:22.374 DEBUG 17572 --- [           main] o.s.web.servlet.DispatcherServlet        : Servlet 'dispatcherServlet' configured successfully
2018-05-23 08:17:22.375 DEBUG 17572 --- [           main] org.eclipse.jetty.servlet.BaseHolder     : Holding class org.eclipse.jetty.servlet.DefaultServlet from java.net.URLClassLoader@59251fa3
2018-05-23 08:17:22.379 DEBUG 17572 --- [           main] org.eclipse.jetty.servlet.ServletHolder  : Servlet.init org.eclipse.jetty.servlet.DefaultServlet@574ae464 for default
2018-05-23 08:17:22.507 DEBUG 17572 --- [           main] o.eclipse.jetty.servlet.DefaultServlet   : resource base = null
2018-05-23 08:17:22.510 DEBUG 17572 --- [           main] o.e.j.util.component.ContainerLifeCycle  : ServerConnector@4e2c22ff{HTTP/1.1,[http/1.1]}{0.0.0.0:8080} added {sun.nio.ch.ServerSocketChannelImpl[/0:0:0:0:0:0:0:0:8080],POJO}
2018-05-23 08:17:22.555 DEBUG 17572 --- [           main] o.e.j.util.component.ContainerLifeCycle  : EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/IDLE/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:22.551+08:00 added {SelectorProducer@21ffd12b,POJO}
2018-05-23 08:17:22.555 DEBUG 17572 --- [           main] o.e.j.util.component.ContainerLifeCycle  : EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/IDLE/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:22.555+08:00 added {QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}],UNMANAGED}
2018-05-23 08:17:22.555 DEBUG 17572 --- [           main] o.e.j.u.thread.strategy.EatWhatYouKill   : EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/IDLE/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:22.555+08:00 created
2018-05-23 08:17:22.556 DEBUG 17572 --- [           main] o.e.j.util.component.ContainerLifeCycle  : org.eclipse.jetty.io.ManagedSelector@b483be8 id=0 keys=-1 selected=-1 updates=0 added {EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/IDLE/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:22.556+08:00,MANAGED}
2018-05-23 08:17:22.556 DEBUG 17572 --- [           main] o.e.j.util.component.ContainerLifeCycle  : SelectorManager@ServerConnector@4e2c22ff{HTTP/1.1,[http/1.1]}{0.0.0.0:8080} added {org.eclipse.jetty.io.ManagedSelector@b483be8 id=0 keys=-1 selected=-1 updates=0,AUTO}
2018-05-23 08:17:22.556 DEBUG 17572 --- [           main] o.e.j.util.component.ContainerLifeCycle  : EatWhatYouKill@558b59d7/SelectorProducer@72b8d3b/IDLE/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:22.556+08:00 added {SelectorProducer@72b8d3b,POJO}
2018-05-23 08:17:22.556 DEBUG 17572 --- [           main] o.e.j.util.component.ContainerLifeCycle  : EatWhatYouKill@558b59d7/SelectorProducer@72b8d3b/IDLE/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:22.556+08:00 added {QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}],UNMANAGED}
2018-05-23 08:17:22.556 DEBUG 17572 --- [           main] o.e.j.u.thread.strategy.EatWhatYouKill   : EatWhatYouKill@558b59d7/SelectorProducer@72b8d3b/IDLE/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:22.556+08:00 created
2018-05-23 08:17:22.557 DEBUG 17572 --- [           main] o.e.j.util.component.ContainerLifeCycle  : org.eclipse.jetty.io.ManagedSelector@72d5a7c7 id=1 keys=-1 selected=-1 updates=0 added {EatWhatYouKill@558b59d7/SelectorProducer@72b8d3b/IDLE/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:22.557+08:00,MANAGED}
2018-05-23 08:17:22.557 DEBUG 17572 --- [           main] o.e.j.util.component.ContainerLifeCycle  : SelectorManager@ServerConnector@4e2c22ff{HTTP/1.1,[http/1.1]}{0.0.0.0:8080} added {org.eclipse.jetty.io.ManagedSelector@72d5a7c7 id=1 keys=-1 selected=-1 updates=0,AUTO}
2018-05-23 08:17:22.564 DEBUG 17572 --- [           main] o.e.jetty.util.thread.QueuedThreadPool   : queue org.eclipse.jetty.io.ManagedSelector$$Lambda$321/2094973890@c8105cb
2018-05-23 08:17:22.565 DEBUG 17572 --- [qtp703720254-15] o.e.jetty.util.thread.QueuedThreadPool   : run org.eclipse.jetty.io.ManagedSelector$$Lambda$321/2094973890@c8105cb
2018-05-23 08:17:22.565 DEBUG 17572 --- [qtp703720254-15] o.e.j.u.thread.strategy.EatWhatYouKill   : EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/IDLE/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=7,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:22.565+08:00 tryProduce false
2018-05-23 08:17:22.566 DEBUG 17572 --- [           main] org.eclipse.jetty.io.ManagedSelector     : Queued change org.eclipse.jetty.io.ManagedSelector$$Lambda$322/452621819@2339fafc on org.eclipse.jetty.io.ManagedSelector@b483be8 id=0 keys=0 selected=0 updates=0
2018-05-23 08:17:22.567 DEBUG 17572 --- [           main] o.e.jetty.util.thread.QueuedThreadPool   : queue org.eclipse.jetty.io.ManagedSelector$$Lambda$321/2094973890@6b43e103
2018-05-23 08:17:22.567 DEBUG 17572 --- [           main] org.eclipse.jetty.io.ManagedSelector     : Queued change org.eclipse.jetty.io.ManagedSelector$$Lambda$322/452621819@792524e0 on org.eclipse.jetty.io.ManagedSelector@72d5a7c7 id=1 keys=0 selected=0 updates=0
2018-05-23 08:17:22.567 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : updateable 1
2018-05-23 08:17:22.568 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : update org.eclipse.jetty.io.ManagedSelector$$Lambda$322/452621819@2339fafc
2018-05-23 08:17:22.568 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : updates 0
2018-05-23 08:17:22.568 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec waiting with 0 keys
2018-05-23 08:17:22.569 DEBUG 17572 --- [           main] o.e.j.util.component.ContainerLifeCycle  : ServerConnector@4e2c22ff{HTTP/1.1,[http/1.1]}{0.0.0.0:8080} added {acceptor-0@2884881e,POJO}
2018-05-23 08:17:22.569 DEBUG 17572 --- [           main] o.e.jetty.util.thread.QueuedThreadPool   : queue acceptor-0@2884881e
2018-05-23 08:17:22.569 DEBUG 17572 --- [qtp703720254-18] o.e.jetty.util.thread.QueuedThreadPool   : run org.eclipse.jetty.io.ManagedSelector$$Lambda$321/2094973890@6b43e103
2018-05-23 08:17:22.569  INFO 17572 --- [           main] o.e.jetty.server.AbstractConnector       : Started ServerConnector@4e2c22ff{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}
2018-05-23 08:17:22.570 DEBUG 17572 --- [qtp703720254-18] o.e.j.u.thread.strategy.EatWhatYouKill   : EatWhatYouKill@558b59d7/SelectorProducer@72b8d3b/IDLE/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=6,q=1}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:22.569+08:00 tryProduce false
2018-05-23 08:17:22.570 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : updateable 1
2018-05-23 08:17:22.570 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : update org.eclipse.jetty.io.ManagedSelector$$Lambda$322/452621819@792524e0
2018-05-23 08:17:22.570 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : updates 0
2018-05-23 08:17:22.570 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 waiting with 0 keys
2018-05-23 08:17:22.570 DEBUG 17572 --- [qtp703720254-16] o.e.jetty.util.thread.QueuedThreadPool   : run acceptor-0@2884881e
2018-05-23 08:17:22.571  INFO 17572 --- [           main] o.s.b.web.embedded.jetty.JettyWebServer  : Jetty started on port(s) 8080 (http/1.1) with context path '/'
2018-05-23 08:17:22.572 DEBUG 17572 --- [           main] o.s.w.c.s.StandardServletEnvironment     : Adding PropertySource 'server.ports' with highest search precedence
2018-05-23 08:17:22.578  INFO 17572 --- [           main] com.example.demo.DemoApplication         : Started DemoApplication in 3.665 seconds (JVM running for 8.287)
2018-05-23 08:17:29.341 DEBUG 17572 --- [}{0.0.0.0:8080}] org.eclipse.jetty.io.ManagedSelector     : Queued change org.eclipse.jetty.io.ManagedSelector$Accept@2f909a0d on org.eclipse.jetty.io.ManagedSelector@72d5a7c7 id=1 keys=0 selected=0 updates=0
2018-05-23 08:17:29.341 DEBUG 17572 --- [}{0.0.0.0:8080}] org.eclipse.jetty.io.ManagedSelector     : wakeup on submit org.eclipse.jetty.io.ManagedSelector@72d5a7c7 id=1 keys=0 selected=0 updates=1
2018-05-23 08:17:29.341 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 woken with none selected
2018-05-23 08:17:29.342 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 woken up from select, 0/0/0 selected
2018-05-23 08:17:29.342 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 processing 0 keys, 1 updates
2018-05-23 08:17:29.342 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : updateable 1
2018-05-23 08:17:29.342 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : update org.eclipse.jetty.io.ManagedSelector$Accept@2f909a0d
2018-05-23 08:17:29.342 DEBUG 17572 --- [qtp703720254-18] o.e.jetty.util.thread.QueuedThreadPool   : queue org.eclipse.jetty.io.ManagedSelector$Accept@2f909a0d
2018-05-23 08:17:29.342 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : updates 0
2018-05-23 08:17:29.342 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 waiting with 1 keys
2018-05-23 08:17:29.342 DEBUG 17572 --- [qtp703720254-17] o.e.jetty.util.thread.QueuedThreadPool   : run org.eclipse.jetty.io.ManagedSelector$Accept@2f909a0d
2018-05-23 08:17:29.355 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.IdleTimeout         : SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=8/30000}{io=0/0,kio=0,kro=0}-><null> idle timeout check, elapsed: 7 ms, remaining: 29993 ms
2018-05-23 08:17:29.402 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.server.HttpChannel     : new HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0} -> SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=55/30000}{io=0/0,kio=0,kro=0}-><null>,null,HttpChannelState@1a8fcb05{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false}
2018-05-23 08:17:29.410 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.server.HttpConnection  : New HTTP Connection HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=63/30000}{io=0/0,kio=0,kro=0}-><null>
2018-05-23 08:17:29.411 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.AbstractEndPoint    : onOpen SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=63/30000}{io=0/0,kio=0,kro=0}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.411 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.AbstractConnection  : onOpen HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=64/30000}{io=0/0,kio=0,kro=0}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.411 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.AbstractConnection  : fillInterested HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=64/30000}{io=0/0,kio=0,kro=0}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.412 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.FillInterest        : interested FillInterest@4a0f5a4e{AC.ReadCB@7ef73d3f{HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=0}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}}}
2018-05-23 08:17:29.412 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.ChannelEndPoint     : changeInterests p=false 0->1 for SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=0}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.412 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.ManagedSelector     : Queued change org.eclipse.jetty.io.ChannelEndPoint$1@7f681242 on org.eclipse.jetty.io.ManagedSelector@72d5a7c7 id=1 keys=1 selected=0 updates=0
2018-05-23 08:17:29.412 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.ManagedSelector     : wakeup on submit org.eclipse.jetty.io.ManagedSelector@72d5a7c7 id=1 keys=1 selected=0 updates=1
2018-05-23 08:17:29.412 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 woken with none selected
2018-05-23 08:17:29.412 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 woken up from select, 0/0/1 selected
2018-05-23 08:17:29.413 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 processing 0 keys, 1 updates
2018-05-23 08:17:29.413 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : updateable 1
2018-05-23 08:17:29.413 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : update org.eclipse.jetty.io.ChannelEndPoint$1@7f681242
2018-05-23 08:17:29.413 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.ManagedSelector     : Created SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=0}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.418 DEBUG 17572 --- [qtp703720254-17] o.e.jetty.util.thread.QueuedThreadPool   : ran org.eclipse.jetty.io.ManagedSelector$Accept@2f909a0d
2018-05-23 08:17:29.419 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ChannelEndPoint     : Key interests updated 0 -> 1 on SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=7/30000}{io=1/1,kio=1,kro=0}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.419 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : updates 0
2018-05-23 08:17:29.419 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 waiting with 1 keys
2018-05-23 08:17:29.419 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 woken up from select, 1/1/1 selected
2018-05-23 08:17:29.419 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 processing 1 keys, 0 updates
2018-05-23 08:17:29.419 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : selected 1 sun.nio.ch.SelectionKeyImpl@21594ea7 SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=8/30000}{io=1/1,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0} 
2018-05-23 08:17:29.419 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ChannelEndPoint     : onSelected 1->0 r=true w=false for SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=8/30000}{io=1/0,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.419 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ChannelEndPoint     : task CEP:SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=8/30000}{io=1/0,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
2018-05-23 08:17:29.420 DEBUG 17572 --- [qtp703720254-18] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=0/4,p=0} tryExecute EatWhatYouKill@558b59d7/SelectorProducer@72b8d3b/PRODUCING/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=5,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:29.419+08:00
2018-05-23 08:17:29.420 DEBUG 17572 --- [qtp703720254-18] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=0/4,p=1} startReservedThread p=1
2018-05-23 08:17:29.420 DEBUG 17572 --- [qtp703720254-18] o.e.jetty.util.thread.QueuedThreadPool   : queue ReservedThreadExecutor@61bbf017{s=0/4,p=1}@55f51462
2018-05-23 08:17:29.421 DEBUG 17572 --- [qtp703720254-18] o.e.j.u.thread.strategy.EatWhatYouKill   : EatWhatYouKill@558b59d7/SelectorProducer@72b8d3b/PRODUCING/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=5,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=1}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:29.421+08:00 m=PRODUCE_EXECUTE_CONSUME t=CEP:SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=10/30000}{io=1/0,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING/BLOCKING
2018-05-23 08:17:29.421 DEBUG 17572 --- [qtp703720254-19] o.e.jetty.util.thread.QueuedThreadPool   : run ReservedThreadExecutor@61bbf017{s=0/4,p=1}@55f51462
2018-05-23 08:17:29.421 DEBUG 17572 --- [qtp703720254-19] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=1/4,p=1}@55f51462 started
2018-05-23 08:17:29.421 DEBUG 17572 --- [qtp703720254-18] o.e.jetty.util.thread.QueuedThreadPool   : queue CEP:SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=10/30000}{io=1/0,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
2018-05-23 08:17:29.421 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : updateable 0
2018-05-23 08:17:29.421 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : updates 0
2018-05-23 08:17:29.421 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ChannelEndPoint     : Key interests updated 1 -> 0 on SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=10/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.421 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 waiting with 1 keys
2018-05-23 08:17:29.421 DEBUG 17572 --- [qtp703720254-19] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=1/4,p=0}@55f51462 waiting
2018-05-23 08:17:29.422 DEBUG 17572 --- [qtp703720254-20] o.e.jetty.util.thread.QueuedThreadPool   : run CEP:SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=10/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
2018-05-23 08:17:29.422 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.FillInterest        : fillable FillInterest@4a0f5a4e{AC.ReadCB@7ef73d3f{HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=11/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}}}
2018-05-23 08:17:29.422 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=11/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0} onFillable enter HttpChannelState@1a8fcb05{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
2018-05-23 08:17:29.423 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.ChannelEndPoint     : filled 75 SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=12/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.423 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0} filled 75 HeapByteBuffer@423260e6[p=0,l=75,c=8192,r=75]={<<<GET / HTTP/1.1\r\nH...Accept: */*\r\n\r\n>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:29.423 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=0,c=false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@423260e6[p=0,l=75,c=8192,r=75]={<<<GET / HTTP/1.1\r\nH...Accept: */*\r\n\r\n>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}
2018-05-23 08:17:29.423 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : parseNext s=START HeapByteBuffer@423260e6[p=0,l=75,c=8192,r=75]={<<<GET / HTTP/1.1\r\nH...Accept: */*\r\n\r\n>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:29.423 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : START --> SPACE1
2018-05-23 08:17:29.425 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : SPACE1 --> URI
2018-05-23 08:17:29.425 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : URI --> SPACE2
2018-05-23 08:17:29.425 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : SPACE2 --> REQUEST_VERSION
2018-05-23 08:17:29.425 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : REQUEST_VERSION --> HEADER
2018-05-23 08:17:29.425 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : HEADER:null --> VALUE
2018-05-23 08:17:29.425 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : HEADER:null --> IN_VALUE
2018-05-23 08:17:29.425 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : HEADER:null --> FIELD
2018-05-23 08:17:29.427 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : HEADER:null --> VALUE
2018-05-23 08:17:29.427 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : HEADER:null --> IN_VALUE
2018-05-23 08:17:29.427 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : HEADER:null --> FIELD
2018-05-23 08:17:29.427 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : HEADER:Accept: */* --> IN_VALUE
2018-05-23 08:17:29.427 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : HEADER:Accept: */* --> FIELD
2018-05-23 08:17:29.427 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : HEADER --> END
2018-05-23 08:17:29.429 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpChannel     : REQUEST for //domain.sf1m/ on HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=//domain.sf1m/,age=1}
GET //domain.sf1m/ HTTP/1.1
Host: domain.sf1m
User-Agent: curl/7.52.1
Accept: */*


2018-05-23 08:17:29.429 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpChannel     : HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=//domain.sf1m/,age=2} onContentComplete
2018-05-23 08:17:29.429 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpChannel     : HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=//domain.sf1m/,age=2} onRequestComplete
2018-05-23 08:17:29.430 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpInput       : HttpInputOverHTTP@7cd93af0[c=0,q=0,[0]=null,s=STREAM] addContent EOF
2018-05-23 08:17:29.430 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=END,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=//domain.sf1m/,age=3}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=7/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=END,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=//domain.sf1m/,age=3} parsed true HttpParser{s=END,0 of -1}
2018-05-23 08:17:29.430 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : releaseRequestBuffer HttpConnection@7ef73d3f[p=HttpParser{s=END,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=//domain.sf1m/,age=3}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=7/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=END,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=//domain.sf1m/,age=3}
2018-05-23 08:17:29.430 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpChannel     : HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=//domain.sf1m/,age=3} handle //domain.sf1m/ 
2018-05-23 08:17:29.430 DEBUG 17572 --- [qtp703720254-20] o.eclipse.jetty.server.HttpChannelState  : handling HttpChannelState@1a8fcb05{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false}
2018-05-23 08:17:29.432 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpChannel     : HttpChannelOverHttp@602c7423{r=1,c=false,a=DISPATCHED,uri=//domain.sf1m/,age=4} action DISPATCH
2018-05-23 08:17:29.433 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.Server          : REQUEST GET / on HttpChannelOverHttp@602c7423{r=1,c=false,a=DISPATCHED,uri=//domain.sf1m/,age=6}
2018-05-23 08:17:29.434 DEBUG 17572 --- [qtp703720254-20] o.e.jetty.server.handler.ContextHandler  : scope null||/ @ o.s.b.w.e.j.JettyEmbeddedWebAppContext@44e3135c{/,[file:///C:/Users/huzhifeng/AppData/Local/Temp/jetty-docbase.8927325934899646870.8080/],AVAILABLE}
2018-05-23 08:17:29.434 DEBUG 17572 --- [qtp703720254-20] o.e.jetty.server.handler.ContextHandler  : context=||/ @ o.s.b.w.e.j.JettyEmbeddedWebAppContext@44e3135c{/,[file:///C:/Users/huzhifeng/AppData/Local/Temp/jetty-docbase.8927325934899646870.8080/],AVAILABLE}
2018-05-23 08:17:29.435 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.session         : sessionHandler=org.eclipse.jetty.server.session.SessionHandler1178057442==dftMaxIdleSec=1800
2018-05-23 08:17:29.435 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.session         : session=null
2018-05-23 08:17:29.435 DEBUG 17572 --- [qtp703720254-20] o.eclipse.jetty.servlet.ServletHandler   : servlet |/|null -> dispatcherServlet@7ef5559e==org.springframework.web.servlet.DispatcherServlet,jsp=null,order=-1,inst=true
2018-05-23 08:17:29.441 DEBUG 17572 --- [qtp703720254-20] o.eclipse.jetty.servlet.ServletHandler   : chain=characterEncodingFilter->hiddenHttpMethodFilter->httpPutFormContentFilter->requestContextFilter->Jetty_WebSocketUpgradeFilter->dispatcherServlet@7ef5559e==org.springframework.web.servlet.DispatcherServlet,jsp=null,order=-1,inst=true
2018-05-23 08:17:29.444 DEBUG 17572 --- [qtp703720254-20] o.eclipse.jetty.servlet.ServletHandler   : call filter characterEncodingFilter
2018-05-23 08:17:29.447 DEBUG 17572 --- [qtp703720254-20] o.eclipse.jetty.servlet.ServletHandler   : call filter hiddenHttpMethodFilter
2018-05-23 08:17:29.447 DEBUG 17572 --- [qtp703720254-20] o.eclipse.jetty.servlet.ServletHandler   : call filter httpPutFormContentFilter
2018-05-23 08:17:29.447 DEBUG 17572 --- [qtp703720254-20] o.eclipse.jetty.servlet.ServletHandler   : call filter requestContextFilter
2018-05-23 08:17:29.450 DEBUG 17572 --- [qtp703720254-20] o.s.b.w.s.f.OrderedRequestContextFilter  : Bound request context to thread: Request(GET //domain.sf1m/)@2da4237b
2018-05-23 08:17:29.450 DEBUG 17572 --- [qtp703720254-20] o.eclipse.jetty.servlet.ServletHandler   : call filter Jetty_WebSocketUpgradeFilter
2018-05-23 08:17:29.450 DEBUG 17572 --- [qtp703720254-20] o.eclipse.jetty.servlet.ServletHandler   : call servlet dispatcherServlet@7ef5559e==org.springframework.web.servlet.DispatcherServlet,jsp=null,order=-1,inst=true
2018-05-23 08:17:29.451 DEBUG 17572 --- [qtp703720254-20] o.s.web.servlet.DispatcherServlet        : DispatcherServlet with name 'dispatcherServlet' processing GET request for [/]
2018-05-23 08:17:29.454 DEBUG 17572 --- [qtp703720254-20] s.w.s.m.m.a.RequestMappingHandlerMapping : Looking up handler method for path /
2018-05-23 08:17:29.455 DEBUG 17572 --- [qtp703720254-20] s.w.s.m.m.a.RequestMappingHandlerMapping : Returning handler method [public java.lang.String com.example.demo.HomeController.home()]
2018-05-23 08:17:29.455 DEBUG 17572 --- [qtp703720254-20] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'homeController'
2018-05-23 08:17:29.455 DEBUG 17572 --- [qtp703720254-20] o.s.web.servlet.DispatcherServlet        : Last-Modified value for [/] is: -1
2018-05-23 08:17:29.477 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpChannel     : sendResponse info=null content=HeapByteBuffer@3459193b[p=0,l=11,c=8192,r=11]={<<<Hello world>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=true committing=true callback=Blocker@240f5282{null}
2018-05-23 08:17:29.477 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpChannel     : COMMIT for / on HttpChannelOverHttp@602c7423{r=1,c=true,a=DISPATCHED,uri=//domain.sf1m/,age=50}
200 null HTTP/1.1
Date: Wed, 23 May 2018 00:17:29 GMT
Content-Type: text/plain;charset=utf-8
Content-Length: 11


2018-05-23 08:17:29.480 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : org.eclipse.jetty.server.HttpConnection$SendCallback@6d712463[PROCESSING][i=HTTP/1.1{s=200,h=3,cl=11},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1734696] generate: NEED_HEADER (null,[p=0,l=11,c=8192,r=11],true)@START
2018-05-23 08:17:29.481 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpGenerator     : generateHeaders HTTP/1.1{s=200,h=3,cl=11} last=true content=HeapByteBuffer@3459193b[p=0,l=11,c=8192,r=11]={<<<Hello world>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:29.481 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpGenerator     : Date: Wed, 23 May 2018 00:17:29 GMT
Content-Type: text/plain;charset=utf-8
Content-Length: 11


2018-05-23 08:17:29.482 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpGenerator     : CONTENT_LENGTH
2018-05-23 08:17:29.483 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : org.eclipse.jetty.server.HttpConnection$SendCallback@6d712463[PROCESSING][i=HTTP/1.1{s=200,h=3,cl=11},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1734696] generate: FLUSH ([p=0,l=116,c=8192,r=116],[p=0,l=11,c=8192,r=11],true)@COMPLETING
2018-05-23 08:17:29.483 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.WriteFlusher        : write: WriteFlusher@5e31f1d7{IDLE}->null [HeapByteBuffer@423260e6[p=0,l=116,c=8192,r=116]={<<<HTTP/1.1 200 OK\r\n...-Length: 11\r\n\r\n>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@3459193b[p=0,l=11,c=8192,r=11]={<<<Hello world>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2018-05-23 08:17:29.483 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.WriteFlusher        : update WriteFlusher@5e31f1d7{WRITING}->null:IDLE-->WRITING
2018-05-23 08:17:29.524 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.ChannelEndPoint     : flushed 127 SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=W,to=101/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=END,0 of -1},g=HttpGenerator@45cecb52{s=COMPLETING}]=>HttpChannelOverHttp@602c7423{r=1,c=true,a=DISPATCHED,uri=//domain.sf1m/,age=97}
2018-05-23 08:17:29.525 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.WriteFlusher        : Flushed=true written=127 remaining=0 WriteFlusher@5e31f1d7{WRITING}->null
2018-05-23 08:17:29.525 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.WriteFlusher        : update WriteFlusher@5e31f1d7{IDLE}->null:WRITING-->IDLE
2018-05-23 08:17:29.525 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : org.eclipse.jetty.server.HttpConnection$SendCallback@6d712463[PROCESSING][i=HTTP/1.1{s=200,h=3,cl=11},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1734696] generate: DONE ([p=116,l=116,c=8192,r=0],[p=11,l=11,c=8192,r=0],true)@END
2018-05-23 08:17:29.527 DEBUG 17572 --- [qtp703720254-20] m.m.a.RequestResponseBodyMethodProcessor : Written [Hello world] as "text/plain" using [org.springframework.http.converter.StringHttpMessageConverter@6326c71d]
2018-05-23 08:17:29.528 DEBUG 17572 --- [qtp703720254-20] o.s.web.servlet.DispatcherServlet        : Null ModelAndView returned to DispatcherServlet with name 'dispatcherServlet': assuming HandlerAdapter completed request handling
2018-05-23 08:17:29.528 DEBUG 17572 --- [qtp703720254-20] o.s.web.servlet.DispatcherServlet        : Successfully completed request
2018-05-23 08:17:29.529 DEBUG 17572 --- [qtp703720254-20] o.s.b.w.s.f.OrderedRequestContextFilter  : Cleared thread-bound request context: Request(GET //domain.sf1m/)@2da4237b
2018-05-23 08:17:29.529 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.session         : FinalSession=null old_session_manager=null this=org.eclipse.jetty.server.session.SessionHandler1178057442==dftMaxIdleSec=1800
2018-05-23 08:17:29.529 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.Server          : handled=true async=false committed=true on HttpChannelOverHttp@602c7423{r=1,c=true,a=DISPATCHED,uri=//domain.sf1m/,age=102}
2018-05-23 08:17:29.530 DEBUG 17572 --- [qtp703720254-20] o.eclipse.jetty.server.HttpChannelState  : unhandle HttpChannelState@1a8fcb05{s=DISPATCHED a=NOT_ASYNC i=true r=IDLE w=false}
2018-05-23 08:17:29.530 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpChannel     : HttpChannelOverHttp@602c7423{r=1,c=true,a=COMPLETING,uri=//domain.sf1m/,age=103} action COMPLETE
2018-05-23 08:17:29.530 DEBUG 17572 --- [qtp703720254-20] o.eclipse.jetty.server.HttpChannelState  : onComplete HttpChannelState@1a8fcb05{s=COMPLETING a=NOT_ASYNC i=false r=IDLE w=false}
2018-05-23 08:17:29.531 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpChannel     : COMPLETE for / written=11
2018-05-23 08:17:29.531 DEBUG 17572 --- [qtp703720254-20] o.eclipse.jetty.server.HttpChannelState  : recycle HttpChannelState@1a8fcb05{s=COMPLETED a=NOT_ASYNC i=false r=IDLE w=false}
2018-05-23 08:17:29.532 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : reset HttpParser{s=END,0 of -1}
2018-05-23 08:17:29.532 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : END --> START
2018-05-23 08:17:29.532 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpChannel     : HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0} handle exit, result COMPLETE
2018-05-23 08:17:29.532 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.ChannelEndPoint     : filled 0 SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=8/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.533 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.ChannelEndPoint     : filled 0 SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=8/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.533 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=9/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0} filled 0 HeapByteBuffer@423260e6[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:29.534 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=10/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@423260e6[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}
2018-05-23 08:17:29.534 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.http.HttpParser        : parseNext s=START HeapByteBuffer@423260e6[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:29.534 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=10/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=START,0 of -1}
2018-05-23 08:17:29.534 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : releaseRequestBuffer HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=10/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.534 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.AbstractConnection  : fillInterested HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=10/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.FillInterest        : interested FillInterest@4a0f5a4e{AC.ReadCB@7ef73d3f{HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}}}
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.ChannelEndPoint     : changeInterests p=false 0->1 for SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.ManagedSelector     : Queued change org.eclipse.jetty.io.ChannelEndPoint$1@7f681242 on org.eclipse.jetty.io.ManagedSelector@72d5a7c7 id=1 keys=1 selected=0 updates=0
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.ManagedSelector     : wakeup on submit org.eclipse.jetty.io.ManagedSelector@72d5a7c7 id=1 keys=1 selected=0 updates=1
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 woken with none selected
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 woken up from select, 0/0/1 selected
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 processing 0 keys, 1 updates
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : updateable 1
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : update org.eclipse.jetty.io.ChannelEndPoint$1@7f681242
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ChannelEndPoint     : Key interests updated 0 -> 1 on SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : updates 0
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 waiting with 1 keys
2018-05-23 08:17:29.535 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@1a8fcb05{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
2018-05-23 08:17:29.536 DEBUG 17572 --- [qtp703720254-20] o.e.jetty.util.thread.QueuedThreadPool   : ran CEP:SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=2/30000}{io=1/1,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
2018-05-23 08:17:29.552 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 woken up from select, 1/1/1 selected
2018-05-23 08:17:29.552 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 processing 1 keys, 0 updates
2018-05-23 08:17:29.553 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ManagedSelector     : selected 1 sun.nio.ch.SelectionKeyImpl@21594ea7 SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=19/30000}{io=1/1,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0} 
2018-05-23 08:17:29.553 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ChannelEndPoint     : onSelected 1->0 r=true w=false for SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=19/30000}{io=1/0,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.553 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ChannelEndPoint     : task CEP:SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=19/30000}{io=1/0,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
2018-05-23 08:17:29.553 DEBUG 17572 --- [qtp703720254-18] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=1/4,p=0} tryExecute EatWhatYouKill@558b59d7/SelectorProducer@72b8d3b/PRODUCING/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=4,q=0}[ReservedThreadExecutor@61bbf017{s=1/4,p=0}][pc=0,pic=0,pec=1,epc=0]@2018-05-23T08:17:29.553+08:00
2018-05-23 08:17:29.554 DEBUG 17572 --- [qtp703720254-18] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=0/4,p=0}@55f51462 offer EatWhatYouKill@558b59d7/SelectorProducer@72b8d3b/PRODUCING/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=4,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=1,epc=0]@2018-05-23T08:17:29.554+08:00
2018-05-23 08:17:29.554 DEBUG 17572 --- [qtp703720254-18] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=0/4,p=1} startReservedThread p=1
2018-05-23 08:17:29.554 DEBUG 17572 --- [qtp703720254-18] o.e.jetty.util.thread.QueuedThreadPool   : queue ReservedThreadExecutor@61bbf017{s=0/4,p=1}@3eb2440d
2018-05-23 08:17:29.554 DEBUG 17572 --- [qtp703720254-18] o.e.j.u.thread.strategy.EatWhatYouKill   : EatWhatYouKill@558b59d7/SelectorProducer@72b8d3b/IDLE/p=true/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=4,q=1}[ReservedThreadExecutor@61bbf017{s=0/4,p=1}][pc=0,pic=0,pec=1,epc=0]@2018-05-23T08:17:29.554+08:00 m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=20/30000}{io=1/0,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING/BLOCKING
2018-05-23 08:17:29.555 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.FillInterest        : fillable FillInterest@4a0f5a4e{AC.ReadCB@7ef73d3f{HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=21/30000}{io=1/0,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}}}
2018-05-23 08:17:29.555 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=21/30000}{io=1/0,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0} onFillable enter HttpChannelState@1a8fcb05{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
2018-05-23 08:17:29.556 DEBUG 17572 --- [qtp703720254-19] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=0/4,p=1}@55f51462 task=EatWhatYouKill@558b59d7/SelectorProducer@72b8d3b/IDLE/p=true/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=3,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=1}][pc=0,pic=0,pec=1,epc=1]@2018-05-23T08:17:29.556+08:00
2018-05-23 08:17:29.556 DEBUG 17572 --- [qtp703720254-22] o.e.jetty.util.thread.QueuedThreadPool   : run ReservedThreadExecutor@61bbf017{s=0/4,p=1}@3eb2440d
2018-05-23 08:17:29.557 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ChannelEndPoint     : filled -1 SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=23/30000}{io=1/0,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.557 DEBUG 17572 --- [qtp703720254-19] o.e.j.u.thread.strategy.EatWhatYouKill   : EatWhatYouKill@558b59d7/SelectorProducer@72b8d3b/IDLE/p=true/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=3,q=0}[ReservedThreadExecutor@61bbf017{s=1/4,p=1}][pc=0,pic=0,pec=1,epc=1]@2018-05-23T08:17:29.557+08:00 tryProduce true
2018-05-23 08:17:29.557 DEBUG 17572 --- [qtp703720254-19] org.eclipse.jetty.io.ManagedSelector     : updateable 0
2018-05-23 08:17:29.557 DEBUG 17572 --- [qtp703720254-19] org.eclipse.jetty.io.ManagedSelector     : updates 0
2018-05-23 08:17:29.557 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.AbstractEndPoint    : shutdownInput SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=23/30000}{io=1/0,kio=1,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.557 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.http.HttpParser        : atEOF HttpParser{s=START,0 of -1}
2018-05-23 08:17:29.557 DEBUG 17572 --- [qtp703720254-19] org.eclipse.jetty.io.ChannelEndPoint     : Key interests updated 1 -> 0 on SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=23/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.557 DEBUG 17572 --- [qtp703720254-19] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@2ba125d7 waiting with 1 keys
2018-05-23 08:17:29.558 DEBUG 17572 --- [qtp703720254-22] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=1/4,p=1}@3eb2440d started
2018-05-23 08:17:29.558 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,ISHUT,fill=-,flush=-,to=23/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0} filled -1 HeapByteBuffer@423260e6[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:29.558 DEBUG 17572 --- [qtp703720254-22] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=1/4,p=0}@3eb2440d waiting
2018-05-23 08:17:29.558 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,ISHUT,fill=-,flush=-,to=24/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=START,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@423260e6[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}
2018-05-23 08:17:29.558 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.http.HttpParser        : parseNext s=START HeapByteBuffer@423260e6[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:29.558 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.http.HttpParser        : START --> CLOSED
2018-05-23 08:17:29.558 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.AbstractEndPoint    : close SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,ISHUT,fill=-,flush=-,to=24/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.559 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.AbstractEndPoint    : close(null) SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,ISHUT,fill=-,flush=-,to=24/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.559 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.ChannelEndPoint     : doClose SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,CLOSED,fill=-,flush=-,to=25/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7ef73d3f[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.567 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.WriteFlusher        : ignored: WriteFlusher@5e31f1d7{IDLE}->null

java.nio.channels.ClosedChannelException: null
	at org.eclipse.jetty.io.WriteFlusher.onClose(WriteFlusher.java:532) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.AbstractEndPoint.onClose(AbstractEndPoint.java:353) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.ChannelEndPoint.onClose(ChannelEndPoint.java:216) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.AbstractEndPoint.doOnClose(AbstractEndPoint.java:225) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:192) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:175) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.AbstractConnection.close(AbstractConnection.java:224) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.server.HttpChannelOverHttp.earlyEOF(HttpChannelOverHttp.java:234) [jetty-server-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:1476) [jetty-http-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.server.HttpConnection.parseRequestBuffer(HttpConnection.java:360) [jetty-server-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:250) [jetty-server-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:132) [jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:760) ~[jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:678) ~[jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at java.lang.Thread.run(Thread.java:748) ~[na:1.8.0_172]

2018-05-23 08:17:29.569 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.io.FillInterest        : onClose FillInterest@4a0f5a4e{null}
2018-05-23 08:17:29.569 DEBUG 17572 --- [qtp703720254-18] o.e.jetty.util.thread.QueuedThreadPool   : queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@47eb9cf9
2018-05-23 08:17:29.570 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,CLOSED,fill=-,flush=-,to=35/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@7ef73d3f[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=CLOSED,0 of -1}
2018-05-23 08:17:29.570 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.server.HttpConnection  : releaseRequestBuffer HttpConnection@7ef73d3f[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,CLOSED,fill=-,flush=-,to=36/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@7ef73d3f[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.570 DEBUG 17572 --- [qtp703720254-21] o.e.jetty.util.thread.QueuedThreadPool   : run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@47eb9cf9
2018-05-23 08:17:29.570 DEBUG 17572 --- [qtp703720254-18] org.eclipse.jetty.server.HttpConnection  : HttpConnection@7ef73d3f[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,CLOSED,fill=-,flush=-,to=36/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@7ef73d3f[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@1a8fcb05{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
2018-05-23 08:17:29.570 DEBUG 17572 --- [qtp703720254-18] o.e.jetty.util.thread.QueuedThreadPool   : ran org.eclipse.jetty.io.ManagedSelector$$Lambda$321/2094973890@6b43e103
2018-05-23 08:17:29.570 DEBUG 17572 --- [qtp703720254-21] org.eclipse.jetty.io.ManagedSelector     : Destroyed SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,CLOSED,fill=-,flush=-,to=36/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@7ef73d3f[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.571 DEBUG 17572 --- [qtp703720254-21] org.eclipse.jetty.io.AbstractConnection  : onClose HttpConnection@7ef73d3f[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3bbec382{/127.0.0.1:63573<->/127.0.0.1:8080,CLOSED,fill=-,flush=-,to=36/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@7ef73d3f[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@45cecb52{s=START}]=>HttpChannelOverHttp@602c7423{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:29.571 DEBUG 17572 --- [qtp703720254-21] o.e.jetty.util.thread.QueuedThreadPool   : ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@47eb9cf9
2018-05-23 08:17:35.051 DEBUG 17572 --- [}{0.0.0.0:8080}] org.eclipse.jetty.io.ManagedSelector     : Queued change org.eclipse.jetty.io.ManagedSelector$Accept@2157618f on org.eclipse.jetty.io.ManagedSelector@b483be8 id=0 keys=0 selected=0 updates=0
2018-05-23 08:17:35.052 DEBUG 17572 --- [}{0.0.0.0:8080}] org.eclipse.jetty.io.ManagedSelector     : wakeup on submit org.eclipse.jetty.io.ManagedSelector@b483be8 id=0 keys=0 selected=0 updates=1
2018-05-23 08:17:35.052 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec woken with none selected
2018-05-23 08:17:35.052 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec woken up from select, 0/0/0 selected
2018-05-23 08:17:35.052 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec processing 0 keys, 1 updates
2018-05-23 08:17:35.052 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : updateable 1
2018-05-23 08:17:35.052 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : update org.eclipse.jetty.io.ManagedSelector$Accept@2157618f
2018-05-23 08:17:35.052 DEBUG 17572 --- [qtp703720254-15] o.e.jetty.util.thread.QueuedThreadPool   : queue org.eclipse.jetty.io.ManagedSelector$Accept@2157618f
2018-05-23 08:17:35.052 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : updates 0
2018-05-23 08:17:35.052 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec waiting with 1 keys
2018-05-23 08:17:35.052 DEBUG 17572 --- [qtp703720254-17] o.e.jetty.util.thread.QueuedThreadPool   : run org.eclipse.jetty.io.ManagedSelector$Accept@2157618f
2018-05-23 08:17:35.053 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.IdleTimeout         : SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=0}-><null> idle timeout check, elapsed: 1 ms, remaining: 29999 ms
2018-05-23 08:17:35.054 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.server.HttpChannel     : new HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0} -> SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=0}-><null>,null,HttpChannelState@3b7be84c{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false}
2018-05-23 08:17:35.055 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.server.HttpConnection  : New HTTP Connection HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=3/30000}{io=0/0,kio=0,kro=0}-><null>
2018-05-23 08:17:35.055 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.AbstractEndPoint    : onOpen SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=3/30000}{io=0/0,kio=0,kro=0}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.055 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.AbstractConnection  : onOpen HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=3/30000}{io=0/0,kio=0,kro=0}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.055 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.AbstractConnection  : fillInterested HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=3/30000}{io=0/0,kio=0,kro=0}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.055 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.FillInterest        : interested FillInterest@954c61a{AC.ReadCB@43e0763{HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=0}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}}}
2018-05-23 08:17:35.057 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.ChannelEndPoint     : changeInterests p=false 0->1 for SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=2/30000}{io=0/1,kio=0,kro=0}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.057 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.ManagedSelector     : Queued change org.eclipse.jetty.io.ChannelEndPoint$1@6d0976f4 on org.eclipse.jetty.io.ManagedSelector@b483be8 id=0 keys=1 selected=0 updates=0
2018-05-23 08:17:35.057 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.ManagedSelector     : wakeup on submit org.eclipse.jetty.io.ManagedSelector@b483be8 id=0 keys=1 selected=0 updates=1
2018-05-23 08:17:35.058 DEBUG 17572 --- [qtp703720254-17] org.eclipse.jetty.io.ManagedSelector     : Created SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=2/30000}{io=0/1,kio=0,kro=0}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.058 DEBUG 17572 --- [qtp703720254-17] o.e.jetty.util.thread.QueuedThreadPool   : ran org.eclipse.jetty.io.ManagedSelector$Accept@2157618f
2018-05-23 08:17:35.058 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec woken with none selected
2018-05-23 08:17:35.058 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec woken up from select, 0/0/1 selected
2018-05-23 08:17:35.058 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec processing 0 keys, 1 updates
2018-05-23 08:17:35.058 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : updateable 1
2018-05-23 08:17:35.058 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : update org.eclipse.jetty.io.ChannelEndPoint$1@6d0976f4
2018-05-23 08:17:35.058 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ChannelEndPoint     : Key interests updated 0 -> 1 on SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=3/30000}{io=1/1,kio=1,kro=0}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.058 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : updates 0
2018-05-23 08:17:35.058 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec waiting with 1 keys
2018-05-23 08:17:35.059 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec woken up from select, 1/1/1 selected
2018-05-23 08:17:35.059 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec processing 1 keys, 0 updates
2018-05-23 08:17:35.059 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : selected 1 sun.nio.ch.SelectionKeyImpl@20d8618 SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=4/30000}{io=1/1,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0} 
2018-05-23 08:17:35.059 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ChannelEndPoint     : onSelected 1->0 r=true w=false for SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=4/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.059 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ChannelEndPoint     : task CEP:SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=4/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
2018-05-23 08:17:35.064 DEBUG 17572 --- [qtp703720254-15] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=1/4,p=0} tryExecute EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/PRODUCING/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=4,q=0}[ReservedThreadExecutor@61bbf017{s=1/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:35.064+08:00
2018-05-23 08:17:35.065 DEBUG 17572 --- [qtp703720254-15] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=0/4,p=0}@3eb2440d offer EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/PRODUCING/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=4,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:35.065+08:00
2018-05-23 08:17:35.065 DEBUG 17572 --- [qtp703720254-15] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=0/4,p=1} startReservedThread p=1
2018-05-23 08:17:35.065 DEBUG 17572 --- [qtp703720254-15] o.e.jetty.util.thread.QueuedThreadPool   : queue ReservedThreadExecutor@61bbf017{s=0/4,p=1}@3ba2f835
2018-05-23 08:17:35.066 DEBUG 17572 --- [qtp703720254-15] o.e.j.u.thread.strategy.EatWhatYouKill   : EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/IDLE/p=true/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=4,q=1}[ReservedThreadExecutor@61bbf017{s=0/4,p=1}][pc=0,pic=0,pec=0,epc=0]@2018-05-23T08:17:35.066+08:00 m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=11/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING/BLOCKING
2018-05-23 08:17:35.066 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.FillInterest        : fillable FillInterest@954c61a{AC.ReadCB@43e0763{HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=11/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}}}
2018-05-23 08:17:35.066 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=11/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0} onFillable enter HttpChannelState@3b7be84c{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
2018-05-23 08:17:35.067 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ChannelEndPoint     : filled 74 SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=11/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.067 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=0/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0} filled 74 HeapByteBuffer@423260e6[p=0,l=74,c=8192,r=74]={<<<GET / HTTP/1.1\r\nH...Accept: */*\r\n\r\n>>>lain;charset=utf-...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:35.067 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=0/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=0,c=false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@423260e6[p=0,l=74,c=8192,r=74]={<<<GET / HTTP/1.1\r\nH...Accept: */*\r\n\r\n>>>lain;charset=utf-...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}
2018-05-23 08:17:35.068 DEBUG 17572 --- [qtp703720254-22] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=0/4,p=1}@3eb2440d task=EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/IDLE/p=true/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=4,q=1}[ReservedThreadExecutor@61bbf017{s=0/4,p=1}][pc=0,pic=0,pec=0,epc=1]@2018-05-23T08:17:35.068+08:00
2018-05-23 08:17:35.068 DEBUG 17572 --- [qtp703720254-20] o.e.jetty.util.thread.QueuedThreadPool   : run ReservedThreadExecutor@61bbf017{s=0/4,p=1}@3ba2f835
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : parseNext s=START HeapByteBuffer@423260e6[p=0,l=74,c=8192,r=74]={<<<GET / HTTP/1.1\r\nH...Accept: */*\r\n\r\n>>>lain;charset=utf-...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : START --> SPACE1
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : SPACE1 --> URI
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : URI --> SPACE2
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : SPACE2 --> REQUEST_VERSION
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : REQUEST_VERSION --> HEADER
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : HEADER:null --> VALUE
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : HEADER:null --> IN_VALUE
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : HEADER:null --> FIELD
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : HEADER:null --> VALUE
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : HEADER:null --> IN_VALUE
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : HEADER:null --> FIELD
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : HEADER:Accept: */* --> IN_VALUE
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : HEADER:Accept: */* --> FIELD
2018-05-23 08:17:35.070 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : HEADER --> END
2018-05-23 08:17:35.071 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpChannel     : REQUEST for //domain.sfm/ on HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=//domain.sfm/,age=1}
GET //domain.sfm/ HTTP/1.1
Host: domain.sfm
User-Agent: curl/7.52.1
Accept: */*


2018-05-23 08:17:35.071 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpChannel     : HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=//domain.sfm/,age=1} onContentComplete
2018-05-23 08:17:35.071 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpChannel     : HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=//domain.sfm/,age=1} onRequestComplete
2018-05-23 08:17:35.071 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpInput       : HttpInputOverHTTP@3eddc306[c=0,q=0,[0]=null,s=STREAM] addContent EOF
2018-05-23 08:17:35.071 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=END,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=//domain.sfm/,age=1}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=4/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=END,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=//domain.sfm/,age=1} parsed true HttpParser{s=END,0 of -1}
2018-05-23 08:17:35.072 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : releaseRequestBuffer HttpConnection@43e0763[p=HttpParser{s=END,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=//domain.sfm/,age=1}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=4/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=END,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=//domain.sfm/,age=2}
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpChannel     : HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=//domain.sfm/,age=6} handle //domain.sfm/ 
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.eclipse.jetty.server.HttpChannelState  : handling HttpChannelState@3b7be84c{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false}
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpChannel     : HttpChannelOverHttp@b960e5a{r=1,c=false,a=DISPATCHED,uri=//domain.sfm/,age=7} action DISPATCH
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.Server          : REQUEST GET / on HttpChannelOverHttp@b960e5a{r=1,c=false,a=DISPATCHED,uri=//domain.sfm/,age=7}
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.e.jetty.server.handler.ContextHandler  : scope null||/ @ o.s.b.w.e.j.JettyEmbeddedWebAppContext@44e3135c{/,[file:///C:/Users/huzhifeng/AppData/Local/Temp/jetty-docbase.8927325934899646870.8080/],AVAILABLE}
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.e.jetty.server.handler.ContextHandler  : context=||/ @ o.s.b.w.e.j.JettyEmbeddedWebAppContext@44e3135c{/,[file:///C:/Users/huzhifeng/AppData/Local/Temp/jetty-docbase.8927325934899646870.8080/],AVAILABLE}
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.session         : sessionHandler=org.eclipse.jetty.server.session.SessionHandler1178057442==dftMaxIdleSec=1800
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.session         : session=null
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.eclipse.jetty.servlet.ServletHandler   : servlet |/|null -> dispatcherServlet@7ef5559e==org.springframework.web.servlet.DispatcherServlet,jsp=null,order=-1,inst=true
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.eclipse.jetty.servlet.ServletHandler   : chain=characterEncodingFilter->hiddenHttpMethodFilter->httpPutFormContentFilter->requestContextFilter->Jetty_WebSocketUpgradeFilter->dispatcherServlet@7ef5559e==org.springframework.web.servlet.DispatcherServlet,jsp=null,order=-1,inst=true
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.eclipse.jetty.servlet.ServletHandler   : call filter characterEncodingFilter
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.eclipse.jetty.servlet.ServletHandler   : call filter hiddenHttpMethodFilter
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.eclipse.jetty.servlet.ServletHandler   : call filter httpPutFormContentFilter
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.eclipse.jetty.servlet.ServletHandler   : call filter requestContextFilter
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.s.b.w.s.f.OrderedRequestContextFilter  : Bound request context to thread: Request(GET //domain.sfm/)@68109ee0
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.eclipse.jetty.servlet.ServletHandler   : call filter Jetty_WebSocketUpgradeFilter
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.eclipse.jetty.servlet.ServletHandler   : call servlet dispatcherServlet@7ef5559e==org.springframework.web.servlet.DispatcherServlet,jsp=null,order=-1,inst=true
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] o.s.web.servlet.DispatcherServlet        : DispatcherServlet with name 'dispatcherServlet' processing GET request for [/]
2018-05-23 08:17:35.073 DEBUG 17572 --- [qtp703720254-20] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=1/4,p=1}@3ba2f835 started
2018-05-23 08:17:35.074 DEBUG 17572 --- [qtp703720254-22] o.e.j.u.thread.strategy.EatWhatYouKill   : EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/IDLE/p=true/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=3,q=0}[ReservedThreadExecutor@61bbf017{s=1/4,p=1}][pc=0,pic=0,pec=0,epc=1]@2018-05-23T08:17:35.074+08:00 tryProduce true
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-15] s.w.s.m.m.a.RequestMappingHandlerMapping : Looking up handler method for path /
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : updateable 0
2018-05-23 08:17:35.077 DEBUG 17572 --- [qtp703720254-20] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=1/4,p=0}@3ba2f835 waiting
2018-05-23 08:17:35.078 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : updates 0
2018-05-23 08:17:35.078 DEBUG 17572 --- [qtp703720254-15] s.w.s.m.m.a.RequestMappingHandlerMapping : Returning handler method [public java.lang.String com.example.demo.HomeController.home()]
2018-05-23 08:17:35.078 DEBUG 17572 --- [qtp703720254-15] o.s.b.f.s.DefaultListableBeanFactory     : Returning cached instance of singleton bean 'homeController'
2018-05-23 08:17:35.078 DEBUG 17572 --- [qtp703720254-15] o.s.web.servlet.DispatcherServlet        : Last-Modified value for [/] is: -1
2018-05-23 08:17:35.078 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ChannelEndPoint     : Key interests updated 1 -> 0 on SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=11/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=END,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=DISPATCHED,uri=//domain.sfm/,age=8}
2018-05-23 08:17:35.078 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec waiting with 1 keys
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpChannel     : sendResponse info=null content=HeapByteBuffer@643f8bc[p=0,l=11,c=8192,r=11]={<<<Hello world>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=true committing=true callback=Blocker@2a8cdb11{null}
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpChannel     : COMMIT for / on HttpChannelOverHttp@b960e5a{r=1,c=true,a=DISPATCHED,uri=//domain.sfm/,age=10}
200 null HTTP/1.1
Date: Wed, 23 May 2018 00:17:35 GMT
Content-Type: text/plain;charset=utf-8
Content-Length: 11


2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : org.eclipse.jetty.server.HttpConnection$SendCallback@7a19e8b5[PROCESSING][i=HTTP/1.1{s=200,h=3,cl=11},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@16929e88] generate: NEED_HEADER (null,[p=0,l=11,c=8192,r=11],true)@START
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpGenerator     : generateHeaders HTTP/1.1{s=200,h=3,cl=11} last=true content=HeapByteBuffer@643f8bc[p=0,l=11,c=8192,r=11]={<<<Hello world>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpGenerator     : Date: Wed, 23 May 2018 00:17:35 GMT
Content-Type: text/plain;charset=utf-8
Content-Length: 11


2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpGenerator     : CONTENT_LENGTH
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : org.eclipse.jetty.server.HttpConnection$SendCallback@7a19e8b5[PROCESSING][i=HTTP/1.1{s=200,h=3,cl=11},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@16929e88] generate: FLUSH ([p=0,l=116,c=8192,r=116],[p=0,l=11,c=8192,r=11],true)@COMPLETING
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.WriteFlusher        : write: WriteFlusher@f8d02fe{IDLE}->null [HeapByteBuffer@423260e6[p=0,l=116,c=8192,r=116]={<<<HTTP/1.1 200 OK\r\n...-Length: 11\r\n\r\n>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@643f8bc[p=0,l=11,c=8192,r=11]={<<<Hello world>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.WriteFlusher        : update WriteFlusher@f8d02fe{WRITING}->null:IDLE-->WRITING
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ChannelEndPoint     : flushed 127 SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=W,to=13/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=END,0 of -1},g=HttpGenerator@328bfacf{s=COMPLETING}]=>HttpChannelOverHttp@b960e5a{r=1,c=true,a=DISPATCHED,uri=//domain.sfm/,age=10}
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.WriteFlusher        : Flushed=true written=127 remaining=0 WriteFlusher@f8d02fe{WRITING}->null
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.WriteFlusher        : update WriteFlusher@f8d02fe{IDLE}->null:WRITING-->IDLE
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : org.eclipse.jetty.server.HttpConnection$SendCallback@7a19e8b5[PROCESSING][i=HTTP/1.1{s=200,h=3,cl=11},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@16929e88] generate: DONE ([p=116,l=116,c=8192,r=0],[p=11,l=11,c=8192,r=0],true)@END
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] m.m.a.RequestResponseBodyMethodProcessor : Written [Hello world] as "text/plain" using [org.springframework.http.converter.StringHttpMessageConverter@6326c71d]
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] o.s.web.servlet.DispatcherServlet        : Null ModelAndView returned to DispatcherServlet with name 'dispatcherServlet': assuming HandlerAdapter completed request handling
2018-05-23 08:17:35.080 DEBUG 17572 --- [qtp703720254-15] o.s.web.servlet.DispatcherServlet        : Successfully completed request
2018-05-23 08:17:35.081 DEBUG 17572 --- [qtp703720254-15] o.s.b.w.s.f.OrderedRequestContextFilter  : Cleared thread-bound request context: Request(GET //domain.sfm/)@68109ee0
2018-05-23 08:17:35.081 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.session         : FinalSession=null old_session_manager=null this=org.eclipse.jetty.server.session.SessionHandler1178057442==dftMaxIdleSec=1800
2018-05-23 08:17:35.084 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.Server          : handled=true async=false committed=true on HttpChannelOverHttp@b960e5a{r=1,c=true,a=DISPATCHED,uri=//domain.sfm/,age=14}
2018-05-23 08:17:35.084 DEBUG 17572 --- [qtp703720254-15] o.eclipse.jetty.server.HttpChannelState  : unhandle HttpChannelState@3b7be84c{s=DISPATCHED a=NOT_ASYNC i=true r=IDLE w=false}
2018-05-23 08:17:35.084 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpChannel     : HttpChannelOverHttp@b960e5a{r=1,c=true,a=COMPLETING,uri=//domain.sfm/,age=14} action COMPLETE
2018-05-23 08:17:35.084 DEBUG 17572 --- [qtp703720254-15] o.eclipse.jetty.server.HttpChannelState  : onComplete HttpChannelState@3b7be84c{s=COMPLETING a=NOT_ASYNC i=false r=IDLE w=false}
2018-05-23 08:17:35.084 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpChannel     : COMPLETE for / written=11
2018-05-23 08:17:35.084 DEBUG 17572 --- [qtp703720254-15] o.eclipse.jetty.server.HttpChannelState  : recycle HttpChannelState@3b7be84c{s=COMPLETED a=NOT_ASYNC i=false r=IDLE w=false}
2018-05-23 08:17:35.084 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : reset HttpParser{s=END,0 of -1}
2018-05-23 08:17:35.084 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : END --> START
2018-05-23 08:17:35.084 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpChannel     : HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0} handle exit, result COMPLETE
2018-05-23 08:17:35.084 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ChannelEndPoint     : filled 0 SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=4/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.084 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ChannelEndPoint     : filled 0 SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=4/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.085 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=5/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0} filled 0 HeapByteBuffer@423260e6[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:35.085 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=5/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@423260e6[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}
2018-05-23 08:17:35.085 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.http.HttpParser        : parseNext s=START HeapByteBuffer@423260e6[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:35.085 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=5/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=START,0 of -1}
2018-05-23 08:17:35.086 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : releaseRequestBuffer HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=6/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.086 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.AbstractConnection  : fillInterested HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=6/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.086 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.FillInterest        : interested FillInterest@954c61a{AC.ReadCB@43e0763{HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}}}
2018-05-23 08:17:35.086 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ChannelEndPoint     : changeInterests p=false 0->1 for SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.087 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : Queued change org.eclipse.jetty.io.ChannelEndPoint$1@6d0976f4 on org.eclipse.jetty.io.ManagedSelector@b483be8 id=0 keys=1 selected=0 updates=0
2018-05-23 08:17:35.087 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.io.ManagedSelector     : wakeup on submit org.eclipse.jetty.io.ManagedSelector@b483be8 id=0 keys=1 selected=0 updates=1
2018-05-23 08:17:35.087 DEBUG 17572 --- [qtp703720254-15] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@3b7be84c{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
2018-05-23 08:17:35.087 DEBUG 17572 --- [qtp703720254-15] o.e.jetty.util.thread.QueuedThreadPool   : ran org.eclipse.jetty.io.ManagedSelector$$Lambda$321/2094973890@c8105cb
2018-05-23 08:17:35.087 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec woken with none selected
2018-05-23 08:17:35.087 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec woken up from select, 0/0/1 selected
2018-05-23 08:17:35.087 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec processing 0 keys, 1 updates
2018-05-23 08:17:35.087 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : updateable 1
2018-05-23 08:17:35.087 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : update org.eclipse.jetty.io.ChannelEndPoint$1@6d0976f4
2018-05-23 08:17:35.087 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ChannelEndPoint     : Key interests updated 0 -> 1 on SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.088 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : updates 0
2018-05-23 08:17:35.088 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec waiting with 1 keys
2018-05-23 08:17:35.103 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec woken up from select, 1/1/1 selected
2018-05-23 08:17:35.103 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec processing 1 keys, 0 updates
2018-05-23 08:17:35.104 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ManagedSelector     : selected 1 sun.nio.ch.SelectionKeyImpl@20d8618 SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=18/30000}{io=1/1,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0} 
2018-05-23 08:17:35.104 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ChannelEndPoint     : onSelected 1->0 r=true w=false for SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=18/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.104 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ChannelEndPoint     : task CEP:SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=18/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
2018-05-23 08:17:35.104 DEBUG 17572 --- [qtp703720254-22] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=1/4,p=0} tryExecute EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/PRODUCING/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=4,q=0}[ReservedThreadExecutor@61bbf017{s=1/4,p=0}][pc=0,pic=0,pec=0,epc=1]@2018-05-23T08:17:35.104+08:00
2018-05-23 08:17:35.104 DEBUG 17572 --- [qtp703720254-22] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=0/4,p=0}@3ba2f835 offer EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/PRODUCING/p=false/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=4,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=0}][pc=0,pic=0,pec=0,epc=1]@2018-05-23T08:17:35.104+08:00
2018-05-23 08:17:35.104 DEBUG 17572 --- [qtp703720254-22] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=0/4,p=1} startReservedThread p=1
2018-05-23 08:17:35.104 DEBUG 17572 --- [qtp703720254-22] o.e.jetty.util.thread.QueuedThreadPool   : queue ReservedThreadExecutor@61bbf017{s=0/4,p=1}@5d9c9467
2018-05-23 08:17:35.104 DEBUG 17572 --- [qtp703720254-18] o.e.jetty.util.thread.QueuedThreadPool   : run ReservedThreadExecutor@61bbf017{s=0/4,p=1}@5d9c9467
2018-05-23 08:17:35.104 DEBUG 17572 --- [qtp703720254-20] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=0/4,p=1}@3ba2f835 task=EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/IDLE/p=true/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=4,q=0}[ReservedThreadExecutor@61bbf017{s=0/4,p=1}][pc=0,pic=0,pec=0,epc=1]@2018-05-23T08:17:35.104+08:00
2018-05-23 08:17:35.104 DEBUG 17572 --- [qtp703720254-18] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=1/4,p=1}@5d9c9467 started
2018-05-23 08:17:35.104 DEBUG 17572 --- [qtp703720254-18] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=1/4,p=0}@5d9c9467 waiting
2018-05-23 08:17:35.105 DEBUG 17572 --- [qtp703720254-22] o.e.j.u.thread.strategy.EatWhatYouKill   : EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/IDLE/p=true/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=3,q=0}[ReservedThreadExecutor@61bbf017{s=1/4,p=0}][pc=0,pic=0,pec=0,epc=1]@2018-05-23T08:17:35.105+08:00 m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=19/30000}{io=1/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING/BLOCKING
2018-05-23 08:17:35.105 DEBUG 17572 --- [qtp703720254-20] o.e.j.u.thread.strategy.EatWhatYouKill   : EatWhatYouKill@752ba51f/SelectorProducer@21ffd12b/IDLE/p=true/QueuedThreadPool[qtp703720254]@29f1eb3e{STARTED,8<=8<=200,i=3,q=0}[ReservedThreadExecutor@61bbf017{s=1/4,p=0}][pc=0,pic=0,pec=0,epc=1]@2018-05-23T08:17:35.105+08:00 tryProduce true
2018-05-23 08:17:35.110 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.ManagedSelector     : updateable 0
2018-05-23 08:17:35.111 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.ManagedSelector     : updates 0
2018-05-23 08:17:35.111 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.FillInterest        : fillable FillInterest@954c61a{AC.ReadCB@43e0763{HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=25/30000}{io=0/0,kio=1,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}}}
2018-05-23 08:17:35.111 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.ChannelEndPoint     : Key interests updated 1 -> 0 on SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=FI,flush=-,to=25/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.111 DEBUG 17572 --- [qtp703720254-20] org.eclipse.jetty.io.ManagedSelector     : Selector sun.nio.ch.WindowsSelectorImpl@7314b1ec waiting with 1 keys
2018-05-23 08:17:35.111 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=25/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0} onFillable enter HttpChannelState@3b7be84c{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
2018-05-23 08:17:35.111 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ChannelEndPoint     : filled -1 SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=25/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.111 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.AbstractEndPoint    : shutdownInput SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,OPEN,fill=-,flush=-,to=25/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.111 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.http.HttpParser        : atEOF HttpParser{s=START,0 of -1}
2018-05-23 08:17:35.111 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,ISHUT,fill=-,flush=-,to=25/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0} filled -1 HeapByteBuffer@423260e6[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:35.111 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,ISHUT,fill=-,flush=-,to=25/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=START,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@423260e6[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}
2018-05-23 08:17:35.111 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.http.HttpParser        : parseNext s=START HeapByteBuffer@423260e6[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2018-05-23 08:17:35.111 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.http.HttpParser        : START --> CLOSED
2018-05-23 08:17:35.112 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.AbstractEndPoint    : close SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,ISHUT,fill=-,flush=-,to=25/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.112 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.AbstractEndPoint    : close(null) SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,ISHUT,fill=-,flush=-,to=26/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.112 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.ChannelEndPoint     : doClose SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,CLOSED,fill=-,flush=-,to=26/30000}{io=0/0,kio=0,kro=1}->HttpConnection@43e0763[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.112 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.WriteFlusher        : ignored: WriteFlusher@f8d02fe{IDLE}->null

java.nio.channels.ClosedChannelException: null
	at org.eclipse.jetty.io.WriteFlusher.onClose(WriteFlusher.java:532) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.AbstractEndPoint.onClose(AbstractEndPoint.java:353) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.ChannelEndPoint.onClose(ChannelEndPoint.java:216) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.AbstractEndPoint.doOnClose(AbstractEndPoint.java:225) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:192) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:175) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.AbstractConnection.close(AbstractConnection.java:224) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.server.HttpChannelOverHttp.earlyEOF(HttpChannelOverHttp.java:234) [jetty-server-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:1476) [jetty-http-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.server.HttpConnection.parseRequestBuffer(HttpConnection.java:360) [jetty-server-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:250) [jetty-server-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [jetty-io-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:760) [jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:678) [jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
	at java.lang.Thread.run(Thread.java:748) [na:1.8.0_172]

2018-05-23 08:17:35.112 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.io.FillInterest        : onClose FillInterest@954c61a{null}
2018-05-23 08:17:35.112 DEBUG 17572 --- [qtp703720254-22] o.e.jetty.util.thread.QueuedThreadPool   : queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@535b71a4
2018-05-23 08:17:35.113 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,CLOSED,fill=-,flush=-,to=27/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@43e0763[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=CLOSED,0 of -1}
2018-05-23 08:17:35.113 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.server.HttpConnection  : releaseRequestBuffer HttpConnection@43e0763[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,CLOSED,fill=-,flush=-,to=27/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@43e0763[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.113 DEBUG 17572 --- [qtp703720254-22] org.eclipse.jetty.server.HttpConnection  : HttpConnection@43e0763[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,CLOSED,fill=-,flush=-,to=27/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@43e0763[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@3b7be84c{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
2018-05-23 08:17:35.113 DEBUG 17572 --- [qtp703720254-22] o.e.j.u.thread.ReservedThreadExecutor    : ReservedThreadExecutor@61bbf017{s=2/4,p=0}@3eb2440d waiting
2018-05-23 08:17:35.114 DEBUG 17572 --- [qtp703720254-21] o.e.jetty.util.thread.QueuedThreadPool   : run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@535b71a4
2018-05-23 08:17:35.114 DEBUG 17572 --- [qtp703720254-21] org.eclipse.jetty.io.ManagedSelector     : Destroyed SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,CLOSED,fill=-,flush=-,to=28/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@43e0763[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.114 DEBUG 17572 --- [qtp703720254-21] org.eclipse.jetty.io.AbstractConnection  : onClose HttpConnection@43e0763[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}<-SocketChannelEndPoint@3831d655{/127.0.0.1:63574<->/127.0.0.1:8080,CLOSED,fill=-,flush=-,to=28/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@43e0763[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@328bfacf{s=START}]=>HttpChannelOverHttp@b960e5a{r=1,c=false,a=IDLE,uri=null,age=0}
2018-05-23 08:17:35.114 DEBUG 17572 --- [qtp703720254-21] o.e.jetty.util.thread.QueuedThreadPool   : ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@535b71a4
ghost changed the title [-]Spring boot web always return 400 when use a domain name[/-] [+][tomcat] Spring boot web always return 400 when use a domain name[/+] on May 23, 2018
ghost

ghost commented on May 23, 2018

@ghost
ghost
on May 23, 2018

Looks like it relate to upstream tomcat issue, i already submit a issue to bz.apache.org

https://bz.apache.org/bugzilla/show_bug.cgi?id=62399

ghost

ghost commented on May 23, 2018

@ghost
ghost
on May 23, 2018

Temporary solution ,replace tomcat with jetty
Using

 <dependency>
            <groupId>org.springframework.boot</groupId>
            <artifactId>spring-boot-starter-web</artifactId>
            <exclusions>
                <exclusion>
                    <groupId>org.springframework.boot</groupId>
                    <artifactId>spring-boot-starter-tomcat</artifactId>
                </exclusion>
            </exclusions>
        </dependency>
        <dependency>
            <groupId>org.springframework.boot</groupId>
            <artifactId>spring-boot-starter-jetty</artifactId>
        </dependency>

Replace

<dependency>
            <groupId>org.springframework.boot</groupId>
            <artifactId>spring-boot-starter-web</artifactId>            
        </dependency>
philwebb
added
for: external-projectFor an external project and not something we can fix
and removed
status: waiting-for-triageAn issue we've not yet triaged
on May 23, 2018
philwebb

philwebb commented on May 23, 2018

@philwebb
philwebb
on May 23, 2018
Member

Thanks, I'll close this one for now and hopefully pick up a fix for Tomcat issue 62399 in due course.

philwebb
closed this as completedon May 23, 2018
ghost

ghost commented on May 23, 2018

@ghost
ghost
on May 23, 2018

@philwebb hello,
Correct reported version.

The alphabetic restriction for the final segment has been removed in:

  • trunk for 9.0.9 onwards
  • 8.5.x for 8.5.32 onwards
  • 8.0.x for 8.0.53 onwards
  • 7.0.x for 7.0.89 onwards

*** This bug has been marked as a duplicate of bug 62371 ***

Upstream got response, may we upgrade our tomcat component

philwebb

philwebb commented on May 23, 2018

@philwebb
philwebb
on May 23, 2018
Member

@netroby We have an automated way to pick up dependency upgrades so we should get to those new Tomcat versions in due course.

philwebb
mentioned this on May 24, 2018
snicoll
mentioned this on Jun 12, 2018
wilkinsona
mentioned this on Jul 2, 2018
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment

Metadata

Metadata

Assignees

No one assigned

    Labels

    for: external-projectFor an external project and not something we can fix

    Type

    No type

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

      Development

      No branches or pull requests

        Participants

        @philwebb@spring-projects-issues

        Issue actions
          [tomcat] Spring boot web always return 400 when use a domain name · Issue #13236 · spring-projects/spring-boot