请求/sys/index ， 日志里面报Remove object 'sysModule' from [app] ，缓存是按照nutzbook配置的

D:\Tomcat\apache-tomcat-9.0.0.M26\bin\catalina.bat run
[2017-10-08 09:08:48,091] Artifact simple_nutz:war exploded: Server is not connected. Deploy is not available.
Using CATALINA_BASE:   "C:\Users\大企鹅\.IntelliJIdea2017.1\system\tomcat\Unnamed_simple_nutz_3"
'#' 不是内部或外部命令，也不是可运行的程序
Using CATALINA_HOME:   "D:\Tomcat\apache-tomcat-9.0.0.M26"
或批处理文件。
Using CATALINA_TMPDIR: "D:\Tomcat\apache-tomcat-9.0.0.M26\temp"
Using JRE_HOME:        "C:\Program Files\Java\jdk1.8.0_101"
Using CLASSPATH:       "D:\Tomcat\apache-tomcat-9.0.0.M26\bin\bootstrap.jar;D:\Tomcat\apache-tomcat-9.0.0.M26\bin\tomcat-juli.jar"
08-Oct-2017 21:08:49.016 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Server version:        Apache Tomcat/9.0.0.M26
08-Oct-2017 21:08:49.016 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Server built:          Aug 2 2017 20:29:05 UTC
08-Oct-2017 21:08:49.016 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Server number:         9.0.0.0
08-Oct-2017 21:08:49.016 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name:               Windows 10
08-Oct-2017 21:08:49.016 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version:            10.0
08-Oct-2017 21:08:49.016 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture:          amd64
08-Oct-2017 21:08:49.016 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home:             C:\Program Files\Java\jdk1.8.0_101\jre
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version:           1.8.0_101-b13
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor:            Oracle Corporation
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE:         C:\Users\大企鹅\.IntelliJIdea2017.1\system\tomcat\Unnamed_simple_nutz_3
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME:         D:\Tomcat\apache-tomcat-9.0.0.M26
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=C:\Users\大企鹅\.IntelliJIdea2017.1\system\tomcat\Unnamed_simple_nutz_3\conf\logging.properties
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.management.jmxremote=
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.management.jmxremote.port=1099
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.management.jmxremote.ssl=false
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.management.jmxremote.authenticate=false
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.rmi.server.hostname=127.0.0.1
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djdk.tls.ephemeralDHKeySize=2048
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=C:\Users\大企鹅\.IntelliJIdea2017.1\system\tomcat\Unnamed_simple_nutz_3
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=D:\Tomcat\apache-tomcat-9.0.0.M26
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=D:\Tomcat\apache-tomcat-9.0.0.M26\temp
08-Oct-2017 21:08:49.031 信息 [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: [C:\Program Files\Java\jdk1.8.0_101\bin;C:\WINDOWS\Sun\Java\bin;C:\WINDOWS\system32;C:\WINDOWS;D:\Tomcat\apache-tomcat-9.0.0.M26\bin;D:\My_SQL\mysql-5.7.19-winx64\bin;D:\Maven\apache-maven-3.5.0\bin;D:\Tomcat\apache-tomcat-9.0.0.M26\bin;C:\Program Files\Java\jdk1.8.0_101\bin;D:\JAVA\Git\cmd;D:\JAVA\nodejs;C:\ProgramData\Oracle\Java\javapath;C:\Program Files (x86)\Intel\iCLS Client\;C:\Program Files\Intel\iCLS Client\;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\Program Files (x86)\Intel\Intel(R) Management Engine Components\DAL;C:\Program Files\Intel\Intel(R) Management Engine Components\DAL;C:\Program Files (x86)\Intel\Intel(R) Management Engine Components\IPT;C:\Program Files\Intel\Intel(R) Management Engine Components\IPT;C:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\Program Files\Intel\WiFi\bin\;C:\Program Files\Common Files\Intel\WirelessCommon\;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\System32\WindowsPowerShell\v1.0\;D:\SVN\bin;D:\SVN\VSVN5.1.1\bin;C:\Users\大企鹅\AppData\Local\Microsoft\WindowsApps;;C:\Users\大企鹅\AppData\Local\Programs\Fiddler;.]
08-Oct-2017 21:08:49.203 信息 [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"]
08-Oct-2017 21:08:49.219 信息 [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read
08-Oct-2017 21:08:49.235 信息 [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["ajp-nio-8009"]
08-Oct-2017 21:08:49.235 信息 [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read
08-Oct-2017 21:08:49.235 信息 [main] org.apache.catalina.startup.Catalina.load Initialization processed in 464 ms
08-Oct-2017 21:08:49.281 信息 [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina]
08-Oct-2017 21:08:49.281 信息 [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet Engine: Apache Tomcat/9.0.0.M26
08-Oct-2017 21:08:49.297 信息 [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"]
08-Oct-2017 21:08:49.328 信息 [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["ajp-nio-8009"]
08-Oct-2017 21:08:49.328 信息 [main] org.apache.catalina.startup.Catalina.start Server startup in 97 ms
Connected to server
[2017-10-08 09:08:49,703] Artifact simple_nutz:war exploded: Artifact is being deployed, please wait...
08-Oct-2017 21:08:53.048 信息 [RMI TCP Connection(3)-127.0.0.1] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
2017-10-08 21:08:53,204 [INFO] org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:133) - Starting Shiro environment initialization.
2017-10-08 21:08:53,673 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: false
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Boolean' value 'false' to type 'Boolean'
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Boolean
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer' value '0' to type 'Byte'
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) -     Converted to Byte value '0'
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value:  
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Character' value ' ' to type 'Character'
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Character
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer' value '0' to type 'Double'
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) -     Converted to Double value '0.0'
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer' value '0' to type 'Float'
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) -     Converted to Float value '0.0'
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer' value '0' to type 'Integer'
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Integer
2017-10-08 21:08:53,688 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer' value '0' to type 'Long'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) -     Converted to Long value '0'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer' value '0' to type 'Short'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) -     Converted to Short value '0'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0.0
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'BigDecimal' value '0.0' to type 'BigDecimal'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a BigDecimal
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'BigInteger' value '0' to type 'BigInteger'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a BigInteger
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: false
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Boolean' value 'false' to type 'Boolean'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Boolean
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer' value '0' to type 'Byte'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) -     Converted to Byte value '0'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value:  
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Character' value ' ' to type 'Character'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Character
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer' value '0' to type 'Double'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) -     Converted to Double value '0.0'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer' value '0' to type 'Float'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) -     Converted to Float value '0.0'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer' value '0' to type 'Integer'
2017-10-08 21:08:53,704 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Integer
2017-10-08 21:08:53,719 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,719 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer' value '0' to type 'Long'
2017-10-08 21:08:53,719 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) -     Converted to Long value '0'
2017-10-08 21:08:53,719 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 0
2017-10-08 21:08:53,719 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer' value '0' to type 'Short'
2017-10-08 21:08:53,719 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) -     Converted to Short value '0'
2017-10-08 21:08:53,719 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: 
2017-10-08 21:08:53,719 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'String' value '' to type 'String'
2017-10-08 21:08:53,719 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Z@37e45950
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'boolean[]' value '[Z@37e45950' to type 'boolean[]'
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a boolean[]
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [B@4757ed9d
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'byte[]' value '[B@4757ed9d' to type 'byte[]'
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a byte[]
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [C@4bda1c4c
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'char[]' value '[C@4bda1c4c' to type 'char[]'
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a char[]
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [D@7e236c2b
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'double[]' value '[D@7e236c2b' to type 'double[]'
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a double[]
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [F@6b188466
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'float[]' value '[F@6b188466' to type 'float[]'
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a float[]
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [I@13dba79d
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'int[]' value '[I@13dba79d' to type 'int[]'
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a int[]
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [J@8f8e1d6
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'long[]' value '[J@8f8e1d6' to type 'long[]'
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a long[]
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [S@6d5f96d6
2017-10-08 21:08:53,735 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'short[]' value '[S@6d5f96d6' to type 'short[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a short[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.math.BigDecimal;@424b04af
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'BigDecimal[]' value '[Ljava.math.BigDecimal;@424b04af' to type 'BigDecimal[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a BigDecimal[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.math.BigInteger;@435b123f
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'BigInteger[]' value '[Ljava.math.BigInteger;@435b123f' to type 'BigInteger[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a BigInteger[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.lang.Boolean;@4857cd23
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Boolean[]' value '[Ljava.lang.Boolean;@4857cd23' to type 'Boolean[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Boolean[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.lang.Byte;@4a5c39ed
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Byte[]' value '[Ljava.lang.Byte;@4a5c39ed' to type 'Byte[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Byte[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.lang.Character;@70d6a0d2
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Character[]' value '[Ljava.lang.Character;@70d6a0d2' to type 'Character[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Character[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.lang.Double;@62c22b19
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Double[]' value '[Ljava.lang.Double;@62c22b19' to type 'Double[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Double[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.lang.Float;@536267e9
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Float[]' value '[Ljava.lang.Float;@536267e9' to type 'Float[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Float[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.lang.Integer;@3e6bc375
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Integer[]' value '[Ljava.lang.Integer;@3e6bc375' to type 'Integer[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Integer[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.lang.Long;@5485d095
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Long[]' value '[Ljava.lang.Long;@5485d095' to type 'Long[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Long[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.lang.Short;@6c561067
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Short[]' value '[Ljava.lang.Short;@6c561067' to type 'Short[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Short[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.lang.String;@47624dc3
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'String[]' value '[Ljava.lang.String;@47624dc3' to type 'String[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a String[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.lang.Class;@7b4f464c
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Class[]' value '[Ljava.lang.Class;@7b4f464c' to type 'Class[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Class[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.util.Date;@27c6a46a
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Date[]' value '[Ljava.util.Date;@27c6a46a' to type 'Date[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Date[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.util.Calendar;@6ee3da4
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'Calendar[]' value '[Ljava.util.Calendar;@6ee3da4' to type 'Calendar[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a Calendar[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.io.File;@1565676f
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'java.io.File[]' value '[Ljava.io.File;@1565676f' to type 'java.io.File[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a java.io.File[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.sql.Date;@a3cefd9
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'java.sql.Date[]' value '[Ljava.sql.Date;@a3cefd9' to type 'java.sql.Date[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a java.sql.Date[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.sql.Time;@206a70c2
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'java.sql.Time[]' value '[Ljava.sql.Time;@206a70c2' to type 'java.sql.Time[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a java.sql.Time[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.sql.Timestamp;@77c19a03
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'java.sql.Timestamp[]' value '[Ljava.sql.Timestamp;@77c19a03' to type 'java.sql.Timestamp[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a java.sql.Timestamp[]
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) - Setting default value: [Ljava.net.URL;@4b08ac92
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'java.net.URL[]' value '[Ljava.net.URL;@4b08ac92' to type 'java.net.URL[]'
2017-10-08 21:08:53,751 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) -     No conversion required, value is already a java.net.URL[]
2017-10-08 21:08:53,782 [DEBUG] org.apache.shiro.web.env.IniWebEnvironment.parseConfig(IniWebEnvironment.java:95) - Checking any specified config locations.
2017-10-08 21:08:53,782 [DEBUG] org.apache.shiro.web.env.IniWebEnvironment.parseConfig(IniWebEnvironment.java:100) - No INI instance or config locations specified.  Trying default config locations.
2017-10-08 21:08:53,798 [DEBUG] org.apache.shiro.io.ResourceUtils.loadFromClassPath(ResourceUtils.java:159) - Opening resource from class path [shiro.ini]
2017-10-08 21:08:53,798 [DEBUG] org.apache.shiro.config.Ini.load(Ini.java:401) - Parsing [main]
2017-10-08 21:08:53,798 [DEBUG] org.apache.shiro.config.Ini.load(Ini.java:401) - Parsing [urls]
2017-10-08 21:08:53,798 [DEBUG] org.apache.shiro.web.env.IniWebEnvironment.getDefaultIni(IniWebEnvironment.java:217) - Discovered non-empty INI configuration at location 'classpath:shiro.ini'.  Using for configuration.
2017-10-08 21:08:53,798 [DEBUG] org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:149) - Creating instance from Ini [sections=main,urls]
2017-10-08 21:08:54,063 [INFO] org.apache.shiro.config.ReflectionBuilder.createNewInstance(ReflectionBuilder.java:314) - An instance with name 'authc' already exists.  Redefining this object as a new instance of type org.nutz.integration.shiro.SimpleAuthenticationFilter
2017-10-08 21:08:54,095 [DEBUG] org.apache.commons.beanutils.ConvertUtilsBean.convert(ConvertUtilsBean.java:481) - Convert string '/WEB-INF/view/error/404.jsp' to class 'java.lang.String'
2017-10-08 21:08:54,095 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'String' value '/WEB-INF/view/error/404.jsp' to type 'String'
2017-10-08 21:08:54,110 [DEBUG] org.apache.commons.beanutils.ConvertUtilsBean.convert(ConvertUtilsBean.java:481) - Convert string '/sys/index' to class 'java.lang.String'
2017-10-08 21:08:54,110 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'String' value '/sys/index' to type 'String'
2017-10-08 21:08:54,110 [DEBUG] org.apache.commons.beanutils.ConvertUtilsBean.convert(ConvertUtilsBean.java:481) - Convert string 'classpath:ehcache.xml' to class 'java.lang.String'
2017-10-08 21:08:54,110 [DEBUG] org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) - Converting 'String' value 'classpath:ehcache.xml' to type 'String'
2017-10-08 21:08:54,110 [DEBUG] org.apache.shiro.cache.ehcache.EhCacheManager.ensureCacheManager(EhCacheManager.java:205) - cacheManager property not set.  Constructing CacheManager instance... 
2017-10-08 21:08:54,126 [DEBUG] org.apache.shiro.io.ResourceUtils.loadFromClassPath(ResourceUtils.java:159) - Opening resource from class path [ehcache.xml]
2017-10-08 21:08:54,126 [DEBUG] net.sf.ehcache.config.ConfigurationFactory.parseConfiguration(ConfigurationFactory.java:150) - Configuring ehcache from InputStream
2017-10-08 21:08:54,173 [DEBUG] net.sf.ehcache.util.PropertyUtil.parseProperties(PropertyUtil.java:87) - propertiesString is null.
2017-10-08 21:08:54,188 [WARN] net.sf.ehcache.CacheManager.configure(CacheManager.java:772) - One or more caches require a DiskStore but there is no diskStore element configured. Using the default disk store path of D:\Tomcat\apache-tomcat-9.0.0.M26\temp. Please explicitly configure the diskStore element in ehcache.xml.
2017-10-08 21:08:54,188 [DEBUG] net.sf.ehcache.config.ConfigurationHelper.createCacheManagerEventListener(ConfigurationHelper.java:189) - No CacheManagerEventListenerFactory class specified. Skipping...
2017-10-08 21:08:54,272 [DEBUG] net.sf.ehcache.Cache.createBootstrapCacheLoader(Cache.java:1044) - No BootstrapCacheLoaderFactory class specified. Skipping...
2017-10-08 21:08:54,272 [DEBUG] net.sf.ehcache.Cache.createCacheWriter(Cache.java:1017) - CacheWriter factory not configured. Skipping...
2017-10-08 21:08:54,272 [DEBUG] net.sf.ehcache.config.ConfigurationHelper.createCacheExceptionHandler(ConfigurationHelper.java:100) - No CacheExceptionHandlerFactory class specified. Skipping...
2017-10-08 21:08:54,288 [DEBUG] net.sf.ehcache.Cache.createBootstrapCacheLoader(Cache.java:1044) - No BootstrapCacheLoaderFactory class specified. Skipping...
2017-10-08 21:08:54,288 [DEBUG] net.sf.ehcache.Cache.createCacheWriter(Cache.java:1017) - CacheWriter factory not configured. Skipping...
2017-10-08 21:08:54,288 [DEBUG] net.sf.ehcache.config.ConfigurationHelper.createCacheExceptionHandler(ConfigurationHelper.java:100) - No CacheExceptionHandlerFactory class specified. Skipping...
2017-10-08 21:08:54,319 [DEBUG] net.sf.ehcache.DiskStorePathManager.resolveAndLockIfNeeded(DiskStorePathManager.java:169) - Using diskstore path D:\Tomcat\apache-tomcat-9.0.0.M26\temp
2017-10-08 21:08:54,319 [DEBUG] net.sf.ehcache.DiskStorePathManager.resolveAndLockIfNeeded(DiskStorePathManager.java:170) - Holding exclusive lock on D:\Tomcat\apache-tomcat-9.0.0.M26\temp\.ehcache-diskstore.lock
2017-10-08 21:08:54,319 [DEBUG] net.sf.ehcache.store.disk.DiskStorageFactory.<init>(DiskStorageFactory.java:170) - Matching data file missing (or empty) for index file. Deleting index file D:\Tomcat\apache-tomcat-9.0.0.M26\temp\shiro-active%0053ession%0043ache.index
2017-10-08 21:08:54,397 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) - Mocking Pass-Through Statistic: LOCAL_OFFHEAP_SIZE
2017-10-08 21:08:54,397 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) - Mocking Pass-Through Statistic: LOCAL_OFFHEAP_SIZE_BYTES
2017-10-08 21:08:54,397 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) - Mocking Pass-Through Statistic: WRITER_QUEUE_LENGTH
2017-10-08 21:08:54,397 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) - Mocking Pass-Through Statistic: REMOTE_SIZE
2017-10-08 21:08:54,397 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) - Mocking Pass-Through Statistic: LAST_REJOIN_TIMESTAMP
2017-10-08 21:08:54,413 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: OFFHEAP_GET
2017-10-08 21:08:54,428 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: OFFHEAP_PUT
2017-10-08 21:08:54,428 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: OFFHEAP_REMOVE
2017-10-08 21:08:54,428 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: XA_COMMIT
2017-10-08 21:08:54,428 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: XA_ROLLBACK
2017-10-08 21:08:54,428 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: XA_RECOVERY
2017-10-08 21:08:54,428 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: CLUSTER_EVENT
2017-10-08 21:08:54,428 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: NONSTOP
2017-10-08 21:08:54,428 [DEBUG] net.sf.ehcache.Cache.initialise(Cache.java:1262) - Initialised cache: shiro-activeSessionCache
2017-10-08 21:08:54,428 [DEBUG] net.sf.ehcache.config.ConfigurationHelper.createCacheDecorators(ConfigurationHelper.java:334) - CacheDecoratorFactory not configured. Skipping for 'shiro-activeSessionCache'.
2017-10-08 21:08:54,428 [DEBUG] net.sf.ehcache.config.ConfigurationHelper.createDefaultCacheDecorators(ConfigurationHelper.java:364) - CacheDecoratorFactory not configured for defaultCache. Skipping for 'shiro-activeSessionCache'.
2017-10-08 21:08:54,428 [DEBUG] org.apache.shiro.cache.ehcache.EhCacheManager.ensureCacheManager(EhCacheManager.java:218) - implicit cacheManager created successfully.
2017-10-08 21:08:54,444 [DEBUG] org.apache.shiro.config.ReflectionBuilder.resolveReference(ReflectionBuilder.java:414) - Encountered object reference '$cacheManager'.  Looking up object with id 'cacheManager'
2017-10-08 21:08:54,444 [DEBUG] org.apache.shiro.realm.AuthorizingRealm.getAuthorizationCacheLazy(AuthorizingRealm.java:234) - No authorizationCache instance set.  Checking for a cacheManager...
2017-10-08 21:08:54,444 [DEBUG] org.apache.shiro.realm.AuthorizingRealm.getAuthorizationCacheLazy(AuthorizingRealm.java:242) - CacheManager [org.apache.shiro.cache.ehcache.EhCacheManager@670f401c] has been configured.  Building authorization cache named [nutzdao_realm.authorizationCache]
2017-10-08 21:08:54,444 [INFO] org.apache.shiro.cache.ehcache.EhCacheManager.getCache(EhCacheManager.java:158) - Cache with name 'nutzdao_realm.authorizationCache' does not yet exist.  Creating now.
2017-10-08 21:08:54,460 [DEBUG] net.sf.ehcache.store.MemoryStore.<init>(MemoryStore.java:180) - Initialized net.sf.ehcache.store.MemoryStore for nutzdao_realm.authorizationCache
2017-10-08 21:08:54,460 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) - Mocking Pass-Through Statistic: LOCAL_OFFHEAP_SIZE
2017-10-08 21:08:54,460 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) - Mocking Pass-Through Statistic: LOCAL_OFFHEAP_SIZE_BYTES
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) - Mocking Pass-Through Statistic: LOCAL_DISK_SIZE
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) - Mocking Pass-Through Statistic: LOCAL_DISK_SIZE_BYTES
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) - Mocking Pass-Through Statistic: WRITER_QUEUE_LENGTH
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) - Mocking Pass-Through Statistic: REMOTE_SIZE
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) - Mocking Pass-Through Statistic: LAST_REJOIN_TIMESTAMP
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: OFFHEAP_GET
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: OFFHEAP_PUT
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: OFFHEAP_REMOVE
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: DISK_GET
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: DISK_PUT
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: DISK_REMOVE
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: XA_COMMIT
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: XA_ROLLBACK
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: XA_RECOVERY
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: CLUSTER_EVENT
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) - Mocking Operation Statistic: NONSTOP
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.Cache.initialise(Cache.java:1262) - Initialised cache: nutzdao_realm.authorizationCache
2017-10-08 21:08:54,475 [DEBUG] net.sf.ehcache.config.ConfigurationHelper.createDefaultCacheDecorators(ConfigurationHelper.java:364) - CacheDecoratorFactory not configured for defaultCache. Skipping for 'nutzdao_realm.authorizationCache'.
2017-10-08 21:08:54,475 [INFO] org.apache.shiro.cache.ehcache.EhCacheManager.getCache(EhCacheManager.java:165) - Added EhCache named [nutzdao_realm.authorizationCache]
2017-10-08 21:08:54,475 [DEBUG] org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:149) - Creating instance from Ini [sections=main,urls]
2017-10-08 21:08:54,475 [DEBUG] org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) - Creating chain [/sys/index] from String definition [anon]
2017-10-08 21:08:54,475 [DEBUG] org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) - Attempting to apply path [/sys/index] to filter [anon] with config [null]
2017-10-08 21:08:54,475 [DEBUG] org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) - Creating chain [/sys/logout] from String definition [logout]
2017-10-08 21:08:54,475 [DEBUG] org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) - Attempting to apply path [/sys/logout] to filter [logout] with config [null]
2017-10-08 21:08:54,475 [DEBUG] org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) - Creating chain [/sys/add/*] from String definition [perms["admin:user"]]
2017-10-08 21:08:54,475 [DEBUG] org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) - Attempting to apply path [/sys/add/*] to filter [perms] with config [admin:user]
2017-10-08 21:08:54,491 [DEBUG] org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) - Creating chain [/rs/*] from String definition [anon]
2017-10-08 21:08:54,491 [DEBUG] org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) - Attempting to apply path [/rs/*] to filter [anon] with config [null]
2017-10-08 21:08:54,491 [DEBUG] org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:142) - Published WebEnvironment as ServletContext attribute with name [org.apache.shiro.web.env.EnvironmentLoader.ENVIRONMENT_ATTRIBUTE_KEY]
2017-10-08 21:08:54,491 [INFO] org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:147) - Shiro environment initialized in 1287 ms.
2017-10-08 21:08:54,522 [INFO] org.nutz.mvc.NutFilter._init(NutFilter.java:85) - NutFilter[nutz] starting ...
2017-10-08 21:08:54,553 [DEBUG] org.nutz.resource.Scans.printLocations(Scans.java:521) - Locations count=4 time use 15ms
2017-10-08 21:08:54,569 [DEBUG] org.nutz.resource.Scans.printLocations(Scans.java:521) - Locations count=46 time use 16ms
2017-10-08 21:08:54,663 [DEBUG] org.nutz.castor.Castors.reload(Castors.java:115) - Using 95 castor for Castors
2017-10-08 21:08:54,678 [DEBUG] org.nutz.mvc.config.AbstractNutConfig.createLoading(AbstractNutConfig.java:59) - Loading by class org.nutz.mvc.impl.NutLoading
2017-10-08 21:08:54,678 [INFO] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:55) - Nutz Version : 1.r.62 
2017-10-08 21:08:54,678 [INFO] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:56) - Nutz.Mvc[nutz] is initializing ...
2017-10-08 21:08:54,678 [DEBUG] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:60) - Web Container Information:
2017-10-08 21:08:54,678 [DEBUG] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:61) -  - Default Charset : GBK
2017-10-08 21:08:54,678 [DEBUG] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:62) -  - Current . path  : D:\Tomcat\apache-tomcat-9.0.0.M26\bin\.
2017-10-08 21:08:54,678 [DEBUG] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:63) -  - Java Version    : 1.8.0_101
2017-10-08 21:08:54,678 [DEBUG] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:64) -  - File separator  : \
2017-10-08 21:08:54,678 [DEBUG] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:65) -  - Timezone        : GMT+08:00
2017-10-08 21:08:54,678 [DEBUG] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:66) -  - OS              : Windows 10 amd64
2017-10-08 21:08:54,678 [DEBUG] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:67) -  - ServerInfo      : Apache Tomcat/9.0.0.M26
2017-10-08 21:08:54,678 [DEBUG] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:68) -  - Servlet API     : 4.0
2017-10-08 21:08:54,678 [DEBUG] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:73) -  - ContextPath     : /simple_nutz
2017-10-08 21:08:54,678 [DEBUG] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:74) -  - context.tempdir : C:\Users\大企鹅\.IntelliJIdea2017.1\system\tomcat\Unnamed_simple_nutz_3\work\Catalina\localhost\simple_nutz
2017-10-08 21:08:54,694 [DEBUG] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:75) -  - MainModule      : cn.chw.sn.MainModule
2017-10-08 21:08:54,694 [DEBUG] org.nutz.mvc.impl.NutLoading.createContext(NutLoading.java:235) - >> app.root = D:/ideaUI/workspace/simple_nutz/target/simple_nutz
2017-10-08 21:08:54,694 [DEBUG] org.nutz.mvc.impl.NutLoading.createIoc(NutLoading.java:376) - @IocBy(type=org.nutz.mvc.ioc.provider.ComboIocProvider, args=["*js", "ioc/", "*anno", "cn.chw.sn", "*tx", "*async"],init=[])
2017-10-08 21:08:54,772 [DEBUG] org.nutz.resource.Scans.scan(Scans.java:274) - Found 2 resource by src( ioc/ ) , regex( ^(.+[.])(js|json)$ )
2017-10-08 21:08:54,772 [DEBUG] org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:49) - loading [dao.js]
2017-10-08 21:08:54,772 [DEBUG] org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:49) - loading [ehcache.js]
2017-10-08 21:08:54,772 [DEBUG] org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:57) - Loaded 6 bean define from path=[ioc/] --> [conf, dao, cacheProvider, dataSource, cacheManager, cacheExecutor]
2017-10-08 21:08:54,788 [DEBUG] org.nutz.resource.Scans.scan(Scans.java:274) - Found 35 resource by src( cn/chw/sn/ ) , regex( ^.+[.]class$ )
2017-10-08 21:08:54,819 [DEBUG] org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) - Found @IocBean : class cn.chw.sn.module.CaptchaModule
2017-10-08 21:08:54,819 [DEBUG] org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) - Found @IocBean : class cn.chw.sn.module.SysModule
2017-10-08 21:08:54,850 [DEBUG] org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) - Found @IocBean : class cn.chw.sn.service.impl.RoleServiceImpl
2017-10-08 21:08:54,850 [DEBUG] org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) - Found @IocBean : class cn.chw.sn.service.impl.UserServiceImpl
2017-10-08 21:08:54,850 [INFO] org.nutz.ioc.loader.annotation.AnnotationIocLoader.<init>(AnnotationIocLoader.java:52) - Found 4 classes in 1 base-packages!
beans = ["sysModule", "userServiceImpl", "captchaModule", "roleServiceImpl"]
2017-10-08 21:08:54,866 [DEBUG] org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:40) - Loaded 5 bean define from reader --
[txREPEATABLE_READ, txSERIALIZABLE, txNONE, txREAD_UNCOMMITTED, txREAD_COMMITTED]
2017-10-08 21:08:54,882 [DEBUG] org.nutz.ioc.aop.SimpleAopMaker.<init>(SimpleAopMaker.java:79) - Load AopConfigure for anno=org.nutz.aop.interceptor.async.Async by type=org.nutz.aop.interceptor.async.AsyncAopIocLoader
2017-10-08 21:08:54,882 [INFO] org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:98) - NutIoc init begin ...
2017-10-08 21:08:54,882 [INFO] org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:115) - ... NutIoc init complete
2017-10-08 21:08:54,897 [INFO] org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:159) - Build URL mapping by org.nutz.mvc.impl.UrlMappingImpl ...
2017-10-08 21:08:54,897 [DEBUG] org.nutz.mvc.impl.NutLoading.createViewMakers(NutLoading.java:366) - @Views(DefaultViewMaker.class)
2017-10-08 21:08:54,913 [DEBUG] org.nutz.mvc.impl.NutLoading.createChainMaker(NutLoading.java:263) - @ChainBy(org.nutz.mvc.impl.NutActionChainMaker)
2017-10-08 21:08:54,913 [DEBUG] org.nutz.mvc.impl.Loadings.scanModules(Loadings.java:154) - module class location 'file:/D:/ideaUI/workspace/simple_nutz/target/simple_nutz/WEB-INF/classes/'
2017-10-08 21:08:54,913 [DEBUG] org.nutz.mvc.impl.Loadings.scanModuleInPackage(Loadings.java:184) -  > scan 'cn.chw.sn'
2017-10-08 21:08:54,928 [DEBUG] org.nutz.resource.Scans.scan(Scans.java:274) - Found 35 resource by src( cn/chw/sn/ ) , regex( ^.+[.]class$ )
2017-10-08 21:08:54,928 [DEBUG] org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:199) -    >> add 'cn.chw.sn.module.CaptchaModule'
2017-10-08 21:08:54,928 [DEBUG] org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:199) -    >> add 'cn.chw.sn.module.SysModule'
2017-10-08 21:08:54,991 [INFO] org.nutz.mvc.impl.NutActionChainMaker.getProcessorByName(NutActionChainMaker.java:72) - Optional processor class not found, disabled : org.nutz.plugins.validation.ValidationProcessor
2017-10-08 21:08:55,022 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/captcha/next'             >> (CaptchaModule.java:26).next          : BufferedImage | @Ok(raw:png) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,022 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/main'                 >> (SysModule.java:66).main              : void       | @Ok(jsp:view.main) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,022 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/add/?'                >> (SysModule.java:147).add              : Object     | @Ok(json:full) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,022 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/register'             >> (SysModule.java:121).register         : void       | @Ok(>>:/login.jsp) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,022 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/info/?/?'             >> (SysModule.java:281).userInfo         : Object     | @Ok(jsp:view.user.info_user) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,022 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/left'                 >> (SysModule.java:76).left              : void       | @Ok(jsp:view.left) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,022 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/code/?'               >> (SysModule.java:325).code             : Object     | @Ok(json:full) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/top'                  >> (SysModule.java:71).top               : void       | @Ok(jsp:view.top) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/u_list'               >> (SysModule.java:183).userList         : Object     | @Ok(jsp:view.user.list) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/r_list'               >> (SysModule.java:222).roleList         : void       | @Ok(jsp:view.role.list) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/find_u'               >> (SysModule.java:202).findUser         : Object     | @Ok(json:full) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/login'                >> (SysModule.java:89).login             : Object     | @Ok(json:full) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/logout'               >> (SysModule.java:107).logout           : Object     | @Ok(>>:/login.jsp) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/add_r'                >> (SysModule.java:233).addRole          : Object     | @Ok(json ) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/welcome'              >> (SysModule.java:81).welcome           : void       | @Ok(jsp:view.welcome) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/index'                >> (SysModule.java:61).loginIndex        : void       | @Ok(jsp:view.login) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/info_modify/?/?'      >> (SysModule.java:302).info_modify      : Object     | @Ok(json:full) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/register_p'           >> (SysModule.java:114).register_p       : void       | @Ok(jsp:view.register) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/info_code'            >> (SysModule.java:319).modifyInfoC      : void       | @Ok(jsp:view.user.code) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,038 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/info_role/?'          >> (SysModule.java:337).roleInfo         : Object     | @Ok(jsp:view.user.info_user) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,053 [INFO] org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) - Init file-pool by: D:/ideaUI/workspace/simple_nutz/target/simple_nutz/WEB-INF/view/file [20000]
2017-10-08 21:08:55,069 [DEBUG] org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) - file-pool.home: 'D:\ideaUI\workspace\simple_nutz\target\simple_nutz\WEB-INF\view\file'
2017-10-08 21:08:55,069 [INFO] org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) - file-pool.cursor: 14
2017-10-08 21:08:55,069 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/uploadAnnex'          >> (SysModule.java:427).uploadAnnex      : void       | @Ok(>>:/sys/userAvatar) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,069 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/n_user'               >> (SysModule.java:215).newUser          : void       | @Ok(jsp:view.user.new) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,069 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/n_role'               >> (SysModule.java:246).newRole          : void       | @Ok(jsp:view.role.new) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,069 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/delete_r'             >> (SysModule.java:268).delete_r         : Object     | @Ok(json:full) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,069 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/modify_u'             >> (SysModule.java:190).modifyUser       : Object     | @Ok(json:full) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,069 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/add_u'                >> (SysModule.java:134).addUser          : Object     | @Ok(json ) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,069 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/del_all'              >> (SysModule.java:167).delAll           : Object     | @Ok(json:full) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,069 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/userAvatar'           >> (SysModule.java:346).userAvatar       : void       | @Ok(jsp:view.user.avatar) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,069 [INFO] org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) - Init file-pool by: D:/ideaUI/workspace/simple_nutz/target/simple_nutz/WEB-INF/view/file [20000]
2017-10-08 21:08:55,069 [DEBUG] org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) - file-pool.home: 'D:\ideaUI\workspace\simple_nutz\target\simple_nutz\WEB-INF\view\file'
2017-10-08 21:08:55,085 [INFO] org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) - file-pool.cursor: 14
2017-10-08 21:08:55,085 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/uploadFile'           >> (SysModule.java:358).uploadFile       : void       | @Ok(>>:/sys/userAvatar) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,085 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/modify_r'             >> (SysModule.java:256).modifyRole       : Object     | @Ok(json:full) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,085 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) -    '/sys/delete_u'             >> (SysModule.java:157).delete_u         : Object     | @Ok(json:full) @Fail(jsp:view.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2017-10-08 21:08:55,085 [INFO] org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:217) - Found 31 module methods
2017-10-08 21:08:55,085 [DEBUG] org.nutz.mvc.impl.NutLoading.evalLocalization(NutLoading.java:331) - @Localization not define
2017-10-08 21:08:55,085 [INFO] org.nutz.mvc.impl.NutLoading.createSessionProvider(NutLoading.java:410) - SessionBy --> org.nutz.integration.shiro.ShiroSessionProvider@3f3a7b4b
2017-10-08 21:08:55,085 [INFO] org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:271) - Setup application...
2017-10-08 21:08:55,085 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'dao'<interface org.nutz.dao.Dao>
2017-10-08 21:08:55,085 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get '$aop_async'<interface org.nutz.ioc.aop.config.AopConfigration>
2017-10-08 21:08:55,085 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) - 	 >> Load definition name=$aop_async
2017-10-08 21:08:55,085 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject($aop_async) in AsyncAopIocLoader@829923527
2017-10-08 21:08:55,085 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) - 	 >> Make...'$aop_async'<interface org.nutz.ioc.aop.config.AopConfigration>
2017-10-08 21:08:55,100 [DEBUG] org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) - Save object '$aop_async' to [app] 
2017-10-08 21:08:55,100 [DEBUG] org.nutz.ioc.aop.SimpleAopMaker.<init>(SimpleAopMaker.java:79) - Load AopConfigure for anno=org.nutz.ioc.aop.Aop by type=org.nutz.ioc.aop.config.impl.AnnotationAopConfigration
2017-10-08 21:08:55,100 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) - 	 >> Load definition name=dao
2017-10-08 21:08:55,100 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=dao
2017-10-08 21:08:55,116 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(dao) in JsonLoader(paths=[ioc/])
2017-10-08 21:08:55,116 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) - 	 >> Make...'dao'<interface org.nutz.dao.Dao>
2017-10-08 21:08:55,132 [DEBUG] org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) - Load class org.nutz.dao.impl.NutDao without AOP
2017-10-08 21:08:55,132 [DEBUG] org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) - Save object 'dao' to [app] 
2017-10-08 21:08:55,132 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'dataSource'<>
2017-10-08 21:08:55,132 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) - 	 >> Load definition name=dataSource
2017-10-08 21:08:55,132 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=dataSource
2017-10-08 21:08:55,147 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(dataSource) in JsonLoader(paths=[ioc/])
2017-10-08 21:08:55,147 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) - 	 >> Make...'dataSource'<>
2017-10-08 21:08:55,163 [DEBUG] org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) - Load class com.alibaba.druid.pool.DruidDataSource without AOP
2017-10-08 21:08:55,163 [DEBUG] org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) - Save object 'dataSource' to [app] 
2017-10-08 21:08:55,163 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'conf'<>
2017-10-08 21:08:55,163 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) - 	 >> Load definition name=conf
2017-10-08 21:08:55,163 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=conf
2017-10-08 21:08:55,163 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(conf) in JsonLoader(paths=[ioc/])
2017-10-08 21:08:55,163 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) - 	 >> Make...'conf'<>
2017-10-08 21:08:55,163 [DEBUG] org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) - Load class org.nutz.ioc.impl.PropertiesProxy without AOP
2017-10-08 21:08:55,163 [DEBUG] org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) - Save object 'conf' to [app] 
2017-10-08 21:08:55,225 [DEBUG] org.nutz.resource.Scans.scan(Scans.java:274) - Found 1 resource by src( custom/ ) , regex( ^.+[.]properties$ )
2017-10-08 21:08:55,225 [DEBUG] org.nutz.ioc.impl.PropertiesProxy.setPaths(PropertiesProxy.java:113) - load properties from File[D:\ideaUI\workspace\simple_nutz\target\simple_nutz\WEB-INF\classes\custom\db.properties]
2017-10-08 21:08:55,665 [INFO] com.alibaba.druid.pool.DruidDataSource.init(DruidDataSource.java:854) - {dataSource-1} inited
2017-10-08 21:08:55,665 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'dataSource'<>
2017-10-08 21:08:55,962 [DEBUG] org.nutz.dao.jdbc.Jdbcs.<clinit>(Jdbcs.java:90) - Jdbcs init complete
2017-10-08 21:08:55,962 [INFO] org.nutz.dao.jdbc.Jdbcs.getExpert(Jdbcs.java:103) - Get Connection from DataSource for JdbcExpert, if you lock at here, check your database server and configure
2017-10-08 21:08:56,212 [DEBUG] org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:188) - select expert : org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert
2017-10-08 21:08:56,212 [DEBUG] org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:199) - JDBC Driver --> mysql-connector-java-6.0.6 ( Revision: 3dab84f4d9bede3cdd14d57b99e9e98a02a5b97d )
2017-10-08 21:08:56,212 [DEBUG] org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:200) - JDBC Name   --> MySQL Connector Java
2017-10-08 21:08:56,212 [DEBUG] org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:202) - JDBC URL    --> jdbc:mysql://127.0.0.1:3306/db_sn?characterEncoding=utf8&useSSL=false&serverTimezone=GMT%2B8
2017-10-08 21:08:56,353 [DEBUG] org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) - Mysql : character_set_client=utf8
2017-10-08 21:08:56,353 [DEBUG] org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) - Mysql : character_set_connection=utf8
2017-10-08 21:08:56,353 [DEBUG] org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) - Mysql : character_set_database=utf8
2017-10-08 21:08:56,353 [DEBUG] org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) - Mysql : character_set_filesystem=binary
2017-10-08 21:08:56,353 [DEBUG] org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) - Mysql : character_set_results=
2017-10-08 21:08:56,353 [DEBUG] org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) - Mysql : character_set_server=utf8
2017-10-08 21:08:56,353 [DEBUG] org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) - Mysql : character_set_system=utf8
2017-10-08 21:08:56,353 [DEBUG] org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) - Mysql : character_sets_dir=D:\My_SQL\mysql-5.7.19-winx64\share\charsets\
2017-10-08 21:08:56,369 [DEBUG] org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:213) - Mysql : binlog_format=ROW
2017-10-08 21:08:56,369 [DEBUG] org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:220) - Mysql : database=db_sn
2017-10-08 21:08:56,369 [DEBUG] org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:226) - Mysql : user=root@localhost
2017-10-08 21:08:56,400 [DEBUG] org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:221) - Database info --> MYSQL:[MySQL - 5.7.19]
2017-10-08 21:08:56,400 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'cacheExecutor'<>
2017-10-08 21:08:56,400 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) - 	 >> Load definition name=cacheExecutor
2017-10-08 21:08:56,400 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=cacheExecutor
2017-10-08 21:08:56,400 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(cacheExecutor) in JsonLoader(paths=[ioc/])
2017-10-08 21:08:56,400 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) - 	 >> Make...'cacheExecutor'<>
2017-10-08 21:08:56,416 [DEBUG] org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) - Load class org.nutz.plugins.cache.dao.DaoCacheInterceptor without AOP
2017-10-08 21:08:56,416 [DEBUG] org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) - Save object 'cacheExecutor' to [app] 
2017-10-08 21:08:56,431 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'cacheProvider'<>
2017-10-08 21:08:56,431 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) - 	 >> Load definition name=cacheProvider
2017-10-08 21:08:56,431 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=cacheProvider
2017-10-08 21:08:56,431 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(cacheProvider) in JsonLoader(paths=[ioc/])
2017-10-08 21:08:56,431 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) - 	 >> Make...'cacheProvider'<>
2017-10-08 21:08:56,431 [DEBUG] org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) - Load class org.nutz.plugins.cache.dao.impl.provider.EhcacheDaoCacheProvider without AOP
2017-10-08 21:08:56,431 [DEBUG] org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) - Save object 'cacheProvider' to [app] 
2017-10-08 21:08:56,431 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'cacheManager'<>
2017-10-08 21:08:56,431 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) - 	 >> Load definition name=cacheManager
2017-10-08 21:08:56,431 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=cacheManager
2017-10-08 21:08:56,431 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(cacheManager) in JsonLoader(paths=[ioc/])
2017-10-08 21:08:56,431 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) - 	 >> Make...'cacheManager'<>
2017-10-08 21:08:56,447 [DEBUG] org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) - Load class net.sf.ehcache.CacheManager without AOP
2017-10-08 21:08:56,447 [DEBUG] org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) - Save object 'cacheManager' to [app] 
2017-10-08 21:08:56,447 [DEBUG] net.sf.ehcache.config.ConfigurationFactory.parseConfiguration(ConfigurationFactory.java:132) - Configuring ehcache from ehcache.xml found in the classpath: file:/D:/ideaUI/workspace/simple_nutz/target/simple_nutz/WEB-INF/classes/ehcache.xml
2017-10-08 21:08:56,447 [DEBUG] net.sf.ehcache.config.ConfigurationFactory.parseConfiguration(ConfigurationFactory.java:98) - Configuring ehcache from URL: file:/D:/ideaUI/workspace/simple_nutz/target/simple_nutz/WEB-INF/classes/ehcache.xml
2017-10-08 21:08:56,447 [DEBUG] net.sf.ehcache.config.ConfigurationFactory.parseConfiguration(ConfigurationFactory.java:150) - Configuring ehcache from InputStream
2017-10-08 21:08:56,462 [DEBUG] org.nutz.resource.Scans.scan(Scans.java:274) - Found 35 resource by src( cn/chw/sn/ ) , regex( ^.+[.]class$ )
2017-10-08 21:08:56,572 [INFO] org.nutz.mvc.impl.NutLoading.load(NutLoading.java:141) - Nutz.Mvc[nutz] is up in 1878ms
2017-10-08 21:08:56,572 [INFO] org.nutz.mvc.NutFilter._init(NutFilter.java:117) - exclusionsPrefix  = ^(/rs/|/druid/|/tld/)
[2017-10-08 09:08:56,603] Artifact simple_nutz:war exploded: Artifact is deployed successfully
[2017-10-08 09:08:56,603] Artifact simple_nutz:war exploded: Deploy took 6,900 milliseconds
2017-10-08 21:08:56,775 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.get(UrlMappingImpl.java:110) - Search mapping for [GET] path=/ : NOT Action match
08-Oct-2017 21:08:59.322 信息 [ContainerBackgroundProcessor[StandardEngine[Catalina]]] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [D:\Tomcat\apache-tomcat-9.0.0.M26\webapps\manager]
08-Oct-2017 21:08:59.353 信息 [ContainerBackgroundProcessor[StandardEngine[Catalina]]] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [D:\Tomcat\apache-tomcat-9.0.0.M26\webapps\manager] has finished in [31] ms
2017-10-08 21:09:02,580 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.get(UrlMappingImpl.java:110) - Search mapping for [GET] path=/ : NOT Action match
2017-10-08 21:09:12,875 [DEBUG] org.nutz.mvc.impl.UrlMappingImpl.get(UrlMappingImpl.java:101) - Found mapping for [GET] path=/sys/index : SysModule.loginIndex(SysModule.java:61)
2017-10-08 21:09:12,877 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'sysModule'<class cn.chw.sn.module.SysModule>
2017-10-08 21:09:12,878 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) - 	 >> Load definition name=sysModule
2017-10-08 21:09:12,878 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(sysModule) in AnnotationIocLoader(packages=[cn.chw.sn])
2017-10-08 21:09:12,878 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) - 	 >> Make...'sysModule'<class cn.chw.sn.module.SysModule>
2017-10-08 21:09:12,884 [DEBUG] org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) - Load class cn.chw.sn.module.SysModule without AOP
2017-10-08 21:09:12,885 [DEBUG] org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) - Save object 'sysModule' to [app] 
2017-10-08 21:09:12,949 [DEBUG] org.nutz.ioc.val.ReferTypeValue.get(ReferTypeValue.java:64) - name=userService not found, search for type=cn.chw.sn.service.UserService
2017-10-08 21:09:12,950 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=conf
2017-10-08 21:09:12,951 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(conf) in JsonLoader(paths=[ioc/])
2017-10-08 21:09:12,951 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=dao
2017-10-08 21:09:12,952 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(dao) in JsonLoader(paths=[ioc/])
2017-10-08 21:09:12,952 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=cacheProvider
2017-10-08 21:09:12,954 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(cacheProvider) in JsonLoader(paths=[ioc/])
2017-10-08 21:09:12,955 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=dataSource
2017-10-08 21:09:12,955 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(dataSource) in JsonLoader(paths=[ioc/])
2017-10-08 21:09:12,955 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=cacheManager
2017-10-08 21:09:12,955 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(cacheManager) in JsonLoader(paths=[ioc/])
2017-10-08 21:09:12,955 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=cacheExecutor
2017-10-08 21:09:12,956 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(cacheExecutor) in JsonLoader(paths=[ioc/])
2017-10-08 21:09:12,956 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(sysModule) in AnnotationIocLoader(packages=[cn.chw.sn])
2017-10-08 21:09:12,956 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(userServiceImpl) in AnnotationIocLoader(packages=[cn.chw.sn])
2017-10-08 21:09:12,956 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'userServiceImpl'<interface cn.chw.sn.service.UserService>
2017-10-08 21:09:12,956 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) - 	 >> Load definition name=userServiceImpl
2017-10-08 21:09:12,956 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(userServiceImpl) in AnnotationIocLoader(packages=[cn.chw.sn])
2017-10-08 21:09:12,956 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) - 	 >> Make...'userServiceImpl'<interface cn.chw.sn.service.UserService>
2017-10-08 21:09:12,956 [DEBUG] org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) - Load class cn.chw.sn.service.impl.UserServiceImpl without AOP
2017-10-08 21:09:12,956 [DEBUG] org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) - Save object 'userServiceImpl' to [app] 
2017-10-08 21:09:13,082 [DEBUG] org.nutz.service.EntityService.<init>(EntityService.java:41) - Get TypeParams for self : cn.chw.sn.po.User
2017-10-08 21:09:13,083 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'dao'<>
2017-10-08 21:09:13,087 [DEBUG] org.nutz.ioc.val.ReferTypeValue.get(ReferTypeValue.java:64) - name=roleService not found, search for type=cn.chw.sn.service.RoleService
2017-10-08 21:09:13,087 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=conf
2017-10-08 21:09:13,087 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(conf) in JsonLoader(paths=[ioc/])
2017-10-08 21:09:13,088 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=dao
2017-10-08 21:09:13,088 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(dao) in JsonLoader(paths=[ioc/])
2017-10-08 21:09:13,088 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=cacheProvider
2017-10-08 21:09:13,088 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(cacheProvider) in JsonLoader(paths=[ioc/])
2017-10-08 21:09:13,088 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=dataSource
2017-10-08 21:09:13,089 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(dataSource) in JsonLoader(paths=[ioc/])
2017-10-08 21:09:13,089 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=cacheManager
2017-10-08 21:09:13,089 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(cacheManager) in JsonLoader(paths=[ioc/])
2017-10-08 21:09:13,089 [DEBUG] org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) - Loading define for name=cacheExecutor
2017-10-08 21:09:13,090 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(cacheExecutor) in JsonLoader(paths=[ioc/])
2017-10-08 21:09:13,090 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(sysModule) in AnnotationIocLoader(packages=[cn.chw.sn])
2017-10-08 21:09:13,090 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(userServiceImpl) in AnnotationIocLoader(packages=[cn.chw.sn])
2017-10-08 21:09:13,093 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(captchaModule) in AnnotationIocLoader(packages=[cn.chw.sn])
2017-10-08 21:09:13,093 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(roleServiceImpl) in AnnotationIocLoader(packages=[cn.chw.sn])
2017-10-08 21:09:13,093 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'roleServiceImpl'<interface cn.chw.sn.service.RoleService>
2017-10-08 21:09:13,093 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) - 	 >> Load definition name=roleServiceImpl
2017-10-08 21:09:13,094 [DEBUG] org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) - Found IocObject(roleServiceImpl) in AnnotationIocLoader(packages=[cn.chw.sn])
2017-10-08 21:09:13,094 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) - 	 >> Make...'roleServiceImpl'<interface cn.chw.sn.service.RoleService>
2017-10-08 21:09:13,094 [DEBUG] org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) - Load class cn.chw.sn.service.impl.RoleServiceImpl without AOP
2017-10-08 21:09:13,094 [DEBUG] org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) - Save object 'roleServiceImpl' to [app] 
2017-10-08 21:09:13,111 [DEBUG] org.nutz.service.EntityService.<init>(EntityService.java:41) - Get TypeParams for self : cn.chw.sn.po.Role
2017-10-08 21:09:13,112 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'dao'<>
2017-10-08 21:09:13,112 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'dao'<interface org.nutz.dao.Dao>
2017-10-08 21:09:13,112 [DEBUG] org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) - Get 'cacheManager'<interface org.apache.shiro.cache.CacheManager>
2017-10-08 21:09:13,118 [DEBUG] org.nutz.ioc.impl.ScopeContext.remove(ScopeContext.java:84) - Remove object 'sysModule' from [app] 
2017-10-08 21:09:13,120 [WARN] org.nutz.mvc.impl.processor.FailProcessor.process(FailProcessor.java:28) - Error@/sys/index :
org.nutz.ioc.IocException: IocBean[sysModule] throw Exception when creating
	at org.nutz.ioc.impl.ObjectMakerImpl.make(ObjectMakerImpl.java:149)
	at org.nutz.ioc.impl.NutIoc.get(NutIoc.java:210)
	at org.nutz.ioc.impl.NutIoc.get(NutIoc.java:239)
	at org.nutz.mvc.impl.processor.ModuleProcessor.process(ModuleProcessor.java:116)
	at org.nutz.mvc.impl.processor.AbstractProcessor.doNext(AbstractProcessor.java:44)
	at org.nutz.mvc.impl.processor.EncodingProcessor.process(EncodingProcessor.java:27)
	at org.nutz.mvc.impl.processor.AbstractProcessor.doNext(AbstractProcessor.java:44)
	at org.nutz.mvc.impl.processor.UpdateRequestAttributesProcessor.process(UpdateRequestAttributesProcessor.java:15)
	at org.nutz.mvc.impl.NutActionChain.doChain(NutActionChain.java:44)
	at org.nutz.mvc.impl.ActionInvoker.invoke(ActionInvoker.java:67)
	at org.nutz.mvc.ActionHandler.handle(ActionHandler.java:31)
	at org.nutz.mvc.NutFilter.doFilter(NutFilter.java:202)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
	at com.alibaba.druid.support.http.WebStatFilter.doFilter(WebStatFilter.java:123)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
	at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:61)
	at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108)
	at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137)
	at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125)
	at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:66)
	at org.apache.shiro.web.servlet.AbstractShiroFilter.executeChain(AbstractShiroFilter.java:449)
	at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:365)
	at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90)
	at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83)
	at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:387)
	at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:362)
	at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:199)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96)
	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:475)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:140)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:80)
	at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:651)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:342)
	at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:498)
	at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66)
	at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:796)
	at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1374)
	at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
	at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.RuntimeException: IocBean[sysModule] fail at field=[cacheManager]
	at org.nutz.ioc.weaver.FieldInjector.inject(FieldInjector.java:40)
	at org.nutz.ioc.weaver.DefaultWeaver.fill(DefaultWeaver.java:56)
	at org.nutz.ioc.impl.ObjectMakerImpl.make(ObjectMakerImpl.java:135)
	... 47 more
Caused by: java.lang.RuntimeException: Fail to set field[cn.chw.sn.module.BaseModule#cacheManager] using value[__DEFAULT__]
	at org.nutz.lang.inject.InjectByField.inject(InjectByField.java:24)
	at org.nutz.ioc.weaver.FieldInjector.inject(FieldInjector.java:33)
	... 49 more
Caused by: org.nutz.castor.FailToCastObjectException: Fail to cast from <net.sf.ehcache.CacheManager> to <org.apache.shiro.cache.CacheManager> for {__DEFAULT__}
	at org.nutz.castor.Castors.cast(Castors.java:262)
	at org.nutz.castor.Castors.castTo(Castors.java:316)
	at org.nutz.lang.inject.InjectByField.inject(InjectByField.java:19)
	... 50 more
Caused by: org.nutz.lang.born.BorningException: Fail to born or cast to 'org.apache.shiro.cache.CacheManager'
 by args: [@(__DEFAULT__)]
 because: No suitable Constructor or Factory Method!!
	at org.nutz.lang.Mirror.born(Mirror.java:988)
	at org.nutz.castor.castor.Object2Object.cast(Object2Object.java:12)
	at org.nutz.castor.Castors.cast(Castors.java:252)
	... 52 more