八月 17, 2016 3:57:29 下午 org.apache.tomcat.util.digester.SetPropertiesRule begin
警告: [SetPropertiesRule]{Server/Service/Engine/Host/Context} Setting property 'source' to 'org.eclipse.jst.jee.server:nutzbook' did not find a matching property.
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: Server version: Apache Tomcat/9.0.0.M9
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: Server built: Jul 4 2016 18:22:47 UTC
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: Server number: 9.0.0.0
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: OS Name: Windows 8.1
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: OS Version: 6.3
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: Architecture: amd64
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: Java Home: C:\Program Files\Java\jre1.8.0_92
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: JVM Version: 1.8.0_92-b14
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: JVM Vendor: Oracle Corporation
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: CATALINA_BASE: F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: CATALINA_HOME: F:\Tomcat\tomcat-9\apache-tomcat-9.0.0.M9
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dcatalina.base=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dcatalina.home=F:\Tomcat\tomcat-9\apache-tomcat-9.0.0.M9
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dwtp.deploy=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Djava.endorsed.dirs=F:\Tomcat\tomcat-9\apache-tomcat-9.0.0.M9\endorsed
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dfile.encoding=UTF-8
八月 17, 2016 3:57:29 下午 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\jre1.8.0_92\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;G:\OracleHome\product\12.1.0\dbhome_2\bin;C:\ProgramData\Oracle\Java\javapath;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files\Intel\WiFi\bin\;C:\Program Files\Common Files\Intel\WirelessCommon\;C:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\Program Files\Java\jdk1.8.0_92\bin;C:\Program Files\TortoiseSVN\bin;G:\OracleHome\product\12.1.0\dbhome_2\BIN;G:\oracle\instantclient-basic-nt-12.1.0.2.0\instantclient_12_1;C:\Program Files\Git\cmd;C:\Program Files (x86)\MySQL\MySQL Fabric 1.5.4 & MySQL Utilities 1.5.4 1.5\;C:\Program Files (x86)\MySQL\MySQL Fabric 1.5.4 & MySQL Utilities 1.5.4 1.5\Doctrine extensions for PHP\;C:\Users\qj\AppData\Local\Programs\Python\Python35-32\Scripts\;C:\Users\qj\AppData\Local\Programs\Python\Python35-32\;C:\Program Files\Intel\WiFi\bin\;C:\Program Files\Common Files\Intel\WirelessCommon\;.
八月 17, 2016 3:57:29 下午 org.apache.coyote.AbstractProtocol init
信息: Initializing ProtocolHandler ["http-nio-8080"]
八月 17, 2016 3:57:29 下午 org.apache.tomcat.util.net.NioSelectorPool getSharedSelector
信息: Using a shared selector for servlet write/read
八月 17, 2016 3:57:29 下午 org.apache.coyote.AbstractProtocol init
信息: Initializing ProtocolHandler ["ajp-nio-8009"]
八月 17, 2016 3:57:29 下午 org.apache.tomcat.util.net.NioSelectorPool getSharedSelector
信息: Using a shared selector for servlet write/read
八月 17, 2016 3:57:29 下午 org.apache.catalina.startup.Catalina load
信息: Initialization processed in 584 ms
八月 17, 2016 3:57:29 下午 org.apache.tomcat.dbcp.dbcp2.BasicDataSourceFactory getObjectInstance
警告: Name = isp Property maxActive is not used in DBCP2, use maxTotal instead. maxTotal default value is 8. You have set value of "20" for "maxActive" property, which is being ignored.
八月 17, 2016 3:57:29 下午 org.apache.tomcat.dbcp.dbcp2.BasicDataSourceFactory getObjectInstance
警告: Name = isp Property maxWait is not used in DBCP2 , use maxWaitMillis instead. maxWaitMillis default value is -1. You have set value of "-1" for "maxWait" property, which is being ignored.
八月 17, 2016 3:57:29 下午 org.apache.tomcat.dbcp.dbcp2.BasicDataSourceFactory getObjectInstance
警告: Name = portal Property maxActive is not used in DBCP2, use maxTotal instead. maxTotal default value is 8. You have set value of "20" for "maxActive" property, which is being ignored.
八月 17, 2016 3:57:29 下午 org.apache.tomcat.dbcp.dbcp2.BasicDataSourceFactory getObjectInstance
警告: Name = portal Property maxWait is not used in DBCP2 , use maxWaitMillis instead. maxWaitMillis default value is -1. You have set value of "-1" for "maxWait" property, which is being ignored.
八月 17, 2016 3:57:29 下午 org.apache.catalina.core.StandardService startInternal
信息: Starting service Catalina
八月 17, 2016 3:57:29 下午 org.apache.catalina.core.StandardEngine startInternal
信息: Starting Servlet Engine: Apache Tomcat/9.0.0.M9
八月 17, 2016 3:57:29 下午 org.apache.catalina.util.SessionIdGeneratorBase createSecureRandom
信息: Creation of SecureRandom instance for session ID generation using [SHA1PRNG] took [193] milliseconds.
八月 17, 2016 3:57:30 下午 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.
八月 17, 2016 3:57:30 下午 org.apache.catalina.core.ApplicationContext log
信息: Initializing Shiro environment
2016-08-17 15:57:30,978 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:128) INFO - Starting Shiro environment initialization.
2016-08-17 15:57:30,995 org.apache.shiro.web.env.IniWebEnvironment.init(IniWebEnvironment.java:76) DEBUG - Checking any specified config locations.
2016-08-17 15:57:30,995 org.apache.shiro.web.env.IniWebEnvironment.init(IniWebEnvironment.java:81) DEBUG - No INI instance or config locations specified. Trying default config locations.
2016-08-17 15:57:31,000 org.apache.shiro.io.ResourceUtils.loadFromClassPath(ResourceUtils.java:159) DEBUG - Opening resource from class path [shiro.ini]
2016-08-17 15:57:31,005 org.apache.shiro.config.Ini.load(Ini.java:351) DEBUG - Parsing [main]
2016-08-17 15:57:31,005 org.apache.shiro.config.Ini.load(Ini.java:351) DEBUG - Parsing [urls]
2016-08-17 15:57:31,007 org.apache.shiro.web.env.IniWebEnvironment.getDefaultIni(IniWebEnvironment.java:136) DEBUG - Discovered non-empty INI configuration at location 'classpath:shiro.ini'. Using for configuration.
2016-08-17 15:57:31,011 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:122) DEBUG - Creating instance from Ini [sections=urls]
2016-08-17 15:57:31,190 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:122) DEBUG - Creating instance from Ini [sections=urls]
2016-08-17 15:57:31,194 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/*] from String definition [anon]
2016-08-17 15:57:31,194 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/*] to filter [anon] with config [null]
2016-08-17 15:57:31,196 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:136) DEBUG - Published WebEnvironment as ServletContext attribute with name [org.apache.shiro.web.env.EnvironmentLoader.ENVIRONMENT_ATTRIBUTE_KEY]
2016-08-17 15:57:31,196 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:141) INFO - Shiro environment initialized in 215 ms.
2016-08-17 15:57:31,241 org.nutz.mvc.NutFilter._init(NutFilter.java:81) INFO - NutFilter[nutz] starting ...
2016-08-17 15:57:31,307 org.nutz.resource.Scans.(Scans.java:490) DEBUG - Locations for Scans:
[JarResourceLocation [jarPath=F:\Tomcat\tomcat-9\apache-tomcat-9.0.0.M9\bin\bootstrap.jar], JarResourceLocation [jarPath=F:\Tomcat\tomcat-9\apache-tomcat-9.0.0.M9\bin\tomcat-juli.jar], FileSystemResourceLocation [root=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\classes], FileSystemResourceLocation [root=F:\eclipse\eclipse], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\nutz-1.r.57.r3.jar]]
2016-08-17 15:57:31,506 org.nutz.resource.Scans.init(Scans.java:75) DEBUG - Locations for Scans:
[JarResourceLocation [jarPath=F:\Tomcat\tomcat-9\apache-tomcat-9.0.0.M9\bin\bootstrap.jar], JarResourceLocation [jarPath=F:\Tomcat\tomcat-9\apache-tomcat-9.0.0.M9\bin\tomcat-juli.jar], FileSystemResourceLocation [root=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\classes], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\taglibs-standard-compat-1.2.5.jar], FileSystemResourceLocation [root=F:\eclipse\eclipse], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\mysql-connector-java-5.1.37.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\nutz-1.r.57.r3.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\commons-logging-1.2.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\nutz-integration-quartz-1.b.53.r3.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\slf4j-log4j12-1.7.21.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\nutz-integration-shiro-1.b.53.r2.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\druid-1.0.16.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\slf4j-api-1.7.21.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\taglibs-standard-impl-1.2.5.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\javax.mail-1.5.2.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\taglibs-standard-jstlel-1.2.5.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\quartz-all-2.1.7.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\shiro-all-1.3.0.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\commons-email-1.3.3.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\taglibs-standard-spec-1.2.5.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\commons-beanutils-1.9.2.jar], JarResourceLocation [jarPath=F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\lib\log4j-1.2.17.jar]]
2016-08-17 15:57:31,511 org.nutz.mvc.config.AbstractNutConfig.getMainModule(AbstractNutConfig.java:128) DEBUG - MainModule: <net.wendal.nutzbook.MainModule>
2016-08-17 15:57:31,526 org.nutz.mvc.config.AbstractNutConfig.createLoading(AbstractNutConfig.java:59) DEBUG - Loading by class org.nutz.mvc.impl.NutLoading
2016-08-17 15:57:31,529 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:55) INFO - Nutz Version : 1.r.57.r3
2016-08-17 15:57:31,529 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:56) INFO - Nutz.Mvc[nutz] is initializing ...
2016-08-17 15:57:31,529 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:60) DEBUG - Web Container Information:
2016-08-17 15:57:31,530 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:61) DEBUG - - Default Charset : UTF-8
2016-08-17 15:57:31,530 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:62) DEBUG - - Current . path : F:\eclipse\eclipse.
2016-08-17 15:57:31,531 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:63) DEBUG - - Java Version : 1.8.0_92
2016-08-17 15:57:31,531 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:64) DEBUG - - File separator : \
2016-08-17 15:57:31,531 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:65) DEBUG - - Timezone : Asia/Shanghai
2016-08-17 15:57:31,531 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:66) DEBUG - - OS : Windows 8.1 amd64
2016-08-17 15:57:31,531 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:67) DEBUG - - ServerInfo : Apache Tomcat/9.0.0.M9
2016-08-17 15:57:31,532 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:68) DEBUG - - Servlet API : 4.0
2016-08-17 15:57:31,532 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:73) DEBUG - - ContextPath : /nutzbook
2016-08-17 15:57:31,532 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:74) DEBUG - - context.tempdir : F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\work\Catalina\localhost\nutzbook
2016-08-17 15:57:31,533 org.nutz.mvc.config.AbstractNutConfig.getMainModule(AbstractNutConfig.java:128) DEBUG - MainModule: <net.wendal.nutzbook.MainModule>
2016-08-17 15:57:31,535 org.nutz.mvc.impl.NutLoading.createContext(NutLoading.java:237) DEBUG - >> app.root = F:/EclipseWork/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/nutzbook
2016-08-17 15:57:31,605 org.nutz.castor.Castors.reload(Castors.java:115) DEBUG - Using 94 castor for Castors
2016-08-17 15:57:31,608 org.nutz.mvc.impl.NutLoading.createIoc(NutLoading.java:378) DEBUG - @IocBy(type=org.nutz.mvc.ioc.provider.ComboIocProvider, args=["*js", "ioc/", "*anno", "net.wendal.nutzbook", "*tx", "*org.nutz.integration.quartz.QuartzIocLoader"],init=[])
2016-08-17 15:57:31,639 org.nutz.resource.Scans.scan(Scans.java:233) DEBUG - Found 2 resource by src( ioc/ ) , regex( ^(.+[.])(js|json)$ )
2016-08-17 15:57:31,639 org.nutz.ioc.loader.json.JsonLoader.(JsonLoader.java:48) DEBUG - loading ioc js config from [dao.js]
2016-08-17 15:57:31,645 org.nutz.ioc.loader.json.JsonLoader.(JsonLoader.java:48) DEBUG - loading ioc js config from [mail.js]
2016-08-17 15:57:31,646 org.nutz.ioc.loader.json.JsonLoader.(JsonLoader.java:56) DEBUG - Loaded 5 bean define from path=[ioc/] --> [conf, dao, emailAuthenticator, dataSource, htmlEmail]
2016-08-17 15:57:31,656 org.nutz.resource.Scans.scan(Scans.java:233) DEBUG - Found 11 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2016-08-17 15:57:31,697 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserModule
2016-08-17 15:57:31,711 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserProfileModule
2016-08-17 15:57:31,725 org.nutz.ioc.loader.annotation.AnnotationIocLoader.(AnnotationIocLoader.java:50) INFO - Found 2 classes in 1 base-packages!
beans = ["userProfileModule", "userModule"]
2016-08-17 15:57:31,727 org.nutz.ioc.loader.json.JsonLoader.(JsonLoader.java:40) DEBUG - Loaded 5 bean define from reader --
[txREPEATABLE_READ, txSERIALIZABLE, txNONE, txREAD_UNCOMMITTED, txREAD_COMMITTED]
2016-08-17 15:57:31,729 org.nutz.ioc.loader.json.JsonLoader.(JsonLoader.java:56) DEBUG - Loaded 0 bean define from path=[] --> []
2016-08-17 15:57:31,734 org.nutz.ioc.impl.NutIoc.(NutIoc.java:98) INFO - NutIoc init begin ...
2016-08-17 15:57:31,737 org.nutz.ioc.impl.NutIoc.(NutIoc.java:115) INFO - ... NutIoc init complete
2016-08-17 15:57:31,737 org.nutz.mvc.config.AbstractNutConfig.getMainModule(AbstractNutConfig.java:128) DEBUG - MainModule: <net.wendal.nutzbook.MainModule>
2016-08-17 15:57:31,740 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:158) INFO - Build URL mapping by org.nutz.mvc.impl.UrlMappingImpl ...
2016-08-17 15:57:31,742 org.nutz.mvc.impl.NutLoading.createViewMakers(NutLoading.java:368) DEBUG - @Views(DefaultViewMaker.class)
2016-08-17 15:57:31,756 org.nutz.resource.Scans.scan(Scans.java:233) DEBUG - Found 1 resource by src( mvc/nutzbook-mvc-chain.js ) , regex( ^(.+[.])(js|json)$ )
2016-08-17 15:57:31,763 org.nutz.mvc.impl.chainconfig.JsonActionChainMakerConfiguretion.(JsonActionChainMakerConfiguretion.java:37) DEBUG - ActionChain Config:
{
"default" :{
"ps" :["net.wendal.nutzbook.mvc.LogTimeProcessor", "org.nutz.mvc.impl.processor.UpdateRequestAttributesProcessor", "org.nutz.mvc.impl.processor.EncodingProcessor", "org.nutz.mvc.impl.processor.ModuleProcessor", "org.nutz.mvc.impl.processor.ActionFiltersProcessor", "org.nutz.mvc.impl.processor.AdaptorProcessor", "org.nutz.mvc.impl.processor.MethodInvokeProcessor", "org.nutz.mvc.impl.processor.ViewProcessor"],
"error" :"org.nutz.mvc.impl.processor.FailProcessor"
}
}
2016-08-17 15:57:31,764 org.nutz.mvc.impl.NutLoading.createChainMaker(NutLoading.java:265) DEBUG - @ChainBy(org.nutz.mvc.impl.NutActionChainMaker)
2016-08-17 15:57:31,768 org.nutz.mvc.impl.Loadings.scanModules(Loadings.java:154) DEBUG - module class location 'file:/F:/EclipseWork/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/nutzbook/WEB-INF/classes/'
2016-08-17 15:57:31,769 org.nutz.mvc.impl.Loadings.scanModuleInPackage(Loadings.java:184) DEBUG - > scan 'net.wendal.nutzbook'
2016-08-17 15:57:31,774 org.nutz.resource.Scans.scan(Scans.java:233) DEBUG - Found 11 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2016-08-17 15:57:31,775 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG - >> add 'net.wendal.nutzbook.module.UserModule'
2016-08-17 15:57:31,775 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG - >> add 'net.wendal.nutzbook.module.UserProfileModule'
2016-08-17 15:57:31,801 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/profile/' >> UserProfileModule.index(...) : UserProfile | @Ok(jsp:jsp.user.profile) @Fail(null ) | by 1 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,801 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/profile/get' >> UserProfileModule.get(...) : UserProfile | @Ok(null ) @Fail(null ) | by 1 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,805 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/profile/update' >> UserProfileModule.update(...) : void | @Ok(void ) @Fail(null ) | by 1 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,808 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/profile/avatar' >> UserProfileModule.readAvatar(...) : Object | @Ok(raw:jpg) @Fail(null ) | by 1 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,816 org.nutz.filepool.NutFilePool.(NutFilePool.java:23) INFO - Init file-pool by: F:/EclipseWork/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/nutzbook/WEB-INF/tmp/user_avatar [20000]
2016-08-17 15:57:31,822 org.nutz.filepool.NutFilePool.(NutFilePool.java:37) DEBUG - file-pool.home: 'F:\EclipseWork.metadata.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\nutzbook\WEB-INF\tmp\user_avatar'
2016-08-17 15:57:31,824 org.nutz.filepool.NutFilePool.(NutFilePool.java:66) INFO - file-pool.cursor: 0
2016-08-17 15:57:31,832 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/profile/avatar' >> UserProfileModule.uploadAvatar(...) : void | @Ok(>>:/user/profile) @Fail(null ) | by 1 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,834 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/' >> UserModule.index(...) : void | @Ok(jsp:jsp.user.list) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,853 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/add' >> UserModule.add(...) : Object | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,854 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/count' >> UserModule.count(...) : int | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,856 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/update' >> UserModule.update(...) : Object | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,857 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/delete' >> UserModule.delete(...) : Object | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,860 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/query' >> UserModule.query(...) : Object | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,861 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/logout' >> UserModule.logout(...) : void | @Ok(>>:/ ) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,861 org.nutz.mvc.impl.UrlMappingImpl.printActionMapping(UrlMappingImpl.java:135) DEBUG - '/user/login' >> UserModule.login(...) : Object | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2016-08-17 15:57:31,862 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:219) INFO - Found 13 module methods
2016-08-17 15:57:31,862 org.nutz.mvc.impl.NutLoading.evalLocalization(NutLoading.java:305) DEBUG - Localization: org.nutz.mvc.impl.NutMessageLoader('msg/') dft
2016-08-17 15:57:31,865 org.nutz.resource.Scans.scan(Scans.java:233) DEBUG - Found 0 resource by src( msg/ ) , regex( ^.+[.]properties$ )
2016-08-17 15:57:31,866 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:27) DEBUG - Load Messages in 0 resource : [[]]
2016-08-17 15:57:31,866 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:95) DEBUG - Message Loaded, size = 0
2016-08-17 15:57:31,867 org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:273) INFO - Setup application...
2016-08-17 15:57:31,867 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dao'<interface org.nutz.dao.Dao>
2016-08-17 15:57:31,869 org.nutz.ioc.aop.SimpleAopMaker.(SimpleAopMaker.java:79) DEBUG - Load AopConfigure for anno=org.nutz.ioc.aop.Aop by type=org.nutz.ioc.aop.config.impl.AnnotationAopConfigration
2016-08-17 15:57:31,870 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - >> Load definition name=dao
2016-08-17 15:57:31,871 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dao
2016-08-17 15:57:31,876 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(dao) in JsonLoader(paths=[ioc/])
2016-08-17 15:57:31,876 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - >> Make...'dao'<interface org.nutz.dao.Dao>
2016-08-17 15:57:31,893 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.dao.impl.NutDao without AOP
2016-08-17 15:57:31,894 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:59) DEBUG - Save object 'dao' to [app]
2016-08-17 15:57:31,897 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2016-08-17 15:57:31,897 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - >> Load definition name=dataSource
2016-08-17 15:57:31,897 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dataSource
2016-08-17 15:57:31,906 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(dataSource) in JsonLoader(paths=[ioc/])
2016-08-17 15:57:31,906 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - >> Make...'dataSource'<>
2016-08-17 15:57:31,926 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class com.alibaba.druid.pool.DruidDataSource without AOP
2016-08-17 15:57:31,926 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:59) DEBUG - Save object 'dataSource' to [app]
2016-08-17 15:57:32,021 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,022 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - >> Load definition name=conf
2016-08-17 15:57:32,022 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=conf
2016-08-17 15:57:32,022 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(conf) in JsonLoader(paths=[ioc/])
2016-08-17 15:57:32,023 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - >> Make...'conf'<>
2016-08-17 15:57:32,024 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.ioc.impl.PropertiesProxy without AOP
2016-08-17 15:57:32,024 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:59) DEBUG - Save object 'conf' to [app]
2016-08-17 15:57:32,029 org.nutz.resource.Scans.scan(Scans.java:233) DEBUG - Found 2 resource by src( custom/ ) , regex( ^.+[.]properties$ )
2016-08-17 15:57:32,035 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,035 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,036 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,036 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,105 com.alibaba.druid.pool.DruidDataSource.init(DruidDataSource.java:669) INFO - {dataSource-1} inited
2016-08-17 15:57:32,107 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2016-08-17 15:57:32,121 org.nutz.dao.impl.NutDao.(NutDao.java:108) DEBUG - NutDao[_selfId=0] init ...
2016-08-17 15:57:32,142 org.nutz.filepool.NutFilePool.(NutFilePool.java:23) INFO - Init file-pool by: C:\Users\qj/.nutz/tmp/dao/ [200000]
2016-08-17 15:57:32,143 org.nutz.filepool.NutFilePool.(NutFilePool.java:37) DEBUG - file-pool.home: 'C:\Users\qj.nutz\tmp\dao'
2016-08-17 15:57:32,144 org.nutz.filepool.NutFilePool.(NutFilePool.java:66) INFO - file-pool.cursor: 0
2016-08-17 15:57:32,154 org.nutz.dao.jdbc.Jdbcs.(Jdbcs.java:90) DEBUG - Jdbcs init complete
2016-08-17 15:57:32,154 org.nutz.dao.jdbc.Jdbcs.getExpert(Jdbcs.java:103) INFO - Get Connection from DataSource for JdbcExpert, if I lock at here, check your database server and configure
2016-08-17 15:57:32,439 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:191) DEBUG - JDBC Driver --> mysql-connector-java-5.1.37 ( Revision: 09940f05b4c98150f352e787a2549f11a2e9da93 )
2016-08-17 15:57:32,439 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:192) DEBUG - JDBC Name --> MySQL Connector Java
2016-08-17 15:57:32,439 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:194) DEBUG - JDBC URL --> jdbc:mysql://127.0.0.1:3306/nutzbook
2016-08-17 15:57:32,616 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:210) DEBUG - Mysql : character_set_client=utf8
2016-08-17 15:57:32,616 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:210) DEBUG - Mysql : character_set_connection=utf8
2016-08-17 15:57:32,617 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:210) DEBUG - Mysql : character_set_database=utf8
2016-08-17 15:57:32,617 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:210) DEBUG - Mysql : character_set_filesystem=binary
2016-08-17 15:57:32,617 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:210) DEBUG - Mysql : character_set_results=
2016-08-17 15:57:32,618 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:210) DEBUG - Mysql : character_set_server=utf8
2016-08-17 15:57:32,618 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:210) DEBUG - Mysql : character_set_system=utf8
2016-08-17 15:57:32,618 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:210) DEBUG - Mysql : character_sets_dir=C:\Program Files\MySQL\MySQL Server 5.6\share\charsets\
2016-08-17 15:57:32,621 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:217) DEBUG - Mysql : database=nutzbook
2016-08-17 15:57:32,624 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:223) DEBUG - Mysql : user=root@localhost
2016-08-17 15:57:32,678 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:238) DEBUG - Database info --> MYSQL:[MySQL - 5.6.25-log]
2016-08-17 15:57:32,687 org.nutz.resource.Scans.scan(Scans.java:233) DEBUG - Found 11 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2016-08-17 15:57:32,772 org.nutz.dao.impl.sql.run.NutDaoExecutor._runSelect(NutDaoExecutor.java:192) DEBUG - SELECT COUNT(*) FROM t_user
2016-08-17 15:57:32,775 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'nutQuartzCronJobFactory'<class org.nutz.integration.quartz.NutQuartzCronJobFactory>
2016-08-17 15:57:32,775 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - >> Load definition name=nutQuartzCronJobFactory
2016-08-17 15:57:32,775 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=nutQuartzCronJobFactory
2016-08-17 15:57:32,776 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(nutQuartzCronJobFactory) in QuartzIocLoader@143663879
2016-08-17 15:57:32,776 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - >> Make...'nutQuartzCronJobFactory'<class org.nutz.integration.quartz.NutQuartzCronJobFactory>
2016-08-17 15:57:32,779 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.integration.quartz.NutQuartzCronJobFactory without AOP
2016-08-17 15:57:32,779 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:59) DEBUG - Save object 'nutQuartzCronJobFactory' to [app]
2016-08-17 15:57:32,780 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'scheduler'<>
2016-08-17 15:57:32,781 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - >> Load definition name=scheduler
2016-08-17 15:57:32,781 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=scheduler
2016-08-17 15:57:32,781 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(scheduler) in QuartzIocLoader@143663879
2016-08-17 15:57:32,781 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - >> Make...'scheduler'<>
2016-08-17 15:57:32,781 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:59) DEBUG - Save object 'scheduler' to [app]
2016-08-17 15:57:32,833 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1175) INFO - Using default implementation for ThreadExecutor
2016-08-17 15:57:32,855 org.quartz.core.SchedulerSignalerImpl.(SchedulerSignalerImpl.java:61) INFO - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2016-08-17 15:57:32,855 org.quartz.core.QuartzScheduler.(QuartzScheduler.java:243) INFO - Quartz Scheduler v.2.1.7 created.
2016-08-17 15:57:32,857 org.quartz.simpl.RAMJobStore.initialize(RAMJobStore.java:154) INFO - RAMJobStore initialized.
2016-08-17 15:57:32,857 org.quartz.core.QuartzScheduler.initialize(QuartzScheduler.java:268) INFO - Scheduler meta-data: Quartz Scheduler (v2.1.7) 'NutzbookScheduler' with instanceId 'NON_CLUSTERED'
Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
NOT STARTED.
Currently in standby mode.
Number of jobs executed: 0
Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 3 threads.
Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.
2016-08-17 15:57:32,857 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1324) INFO - Quartz scheduler 'NutzbookScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
2016-08-17 15:57:32,857 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1328) INFO - Quartz scheduler version: 2.1.7
2016-08-17 15:57:32,857 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'jobFactory'<>
2016-08-17 15:57:32,858 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - >> Load definition name=jobFactory
2016-08-17 15:57:32,858 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=jobFactory
2016-08-17 15:57:32,858 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(jobFactory) in QuartzIocLoader@143663879
2016-08-17 15:57:32,858 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - >> Make...'jobFactory'<>
2016-08-17 15:57:32,860 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.integration.quartz.NutQuartzJobFactory without AOP
2016-08-17 15:57:32,860 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:59) DEBUG - Save object 'jobFactory' to [app]
2016-08-17 15:57:32,860 org.quartz.core.QuartzScheduler.setJobFactory(QuartzScheduler.java:2247) INFO - JobFactory set to: org.nutz.integration.quartz.NutQuartzJobFactory@4bb605c8
2016-08-17 15:57:32,860 org.quartz.core.QuartzScheduler.start(QuartzScheduler.java:534) INFO - Scheduler NutzbookScheduler_$_NON_CLUSTERED started.
2016-08-17 15:57:32,861 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:268) DEBUG - batch acquisition of 0 triggers
2016-08-17 15:57:32,861 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,864 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'htmlEmail'<class org.apache.commons.mail.HtmlEmail>
2016-08-17 15:57:32,865 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - >> Load definition name=htmlEmail
2016-08-17 15:57:32,865 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=htmlEmail
2016-08-17 15:57:32,866 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(htmlEmail) in JsonLoader(paths=[ioc/])
2016-08-17 15:57:32,866 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - >> Make...'htmlEmail'<class org.apache.commons.mail.HtmlEmail>
2016-08-17 15:57:32,878 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.apache.commons.mail.ImageHtmlEmail without AOP
2016-08-17 15:57:32,881 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,882 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,882 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'emailAuthenticator'<>
2016-08-17 15:57:32,882 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - >> Load definition name=emailAuthenticator
2016-08-17 15:57:32,882 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=emailAuthenticator
2016-08-17 15:57:32,882 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(emailAuthenticator) in JsonLoader(paths=[ioc/])
2016-08-17 15:57:32,882 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - >> Make...'emailAuthenticator'<>
2016-08-17 15:57:32,883 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.apache.commons.mail.DefaultAuthenticator without AOP
2016-08-17 15:57:32,883 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:59) DEBUG - Save object 'emailAuthenticator' to [app]
2016-08-17 15:57:32,884 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,884 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,884 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,884 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,884 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,884 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2016-08-17 15:57:32,887 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
org.apache.commons.mail.EmailException: Sending the email to the following server failed : smtp.exmail.qq.com:465
at org.apache.commons.mail.Email.sendMimeMessage(Email.java:1410)
at net.wendal.nutzbook.MainSetup.init(MainSetup.java:43)
at org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:276)
at org.nutz.mvc.impl.NutLoading.load(NutLoading.java:120)
at org.nutz.mvc.ActionHandler.(ActionHandler.java:19)
at org.nutz.mvc.NutFilter._init(NutFilter.java:87)
at org.nutz.mvc.NutFilter.init(NutFilter.java:65)
at net.wendal.nutzbook.mvc.NutzBookNutFilter.init(NutzBookNutFilter.java:22)
at org.apache.catalina.core.ApplicationFilterConfig.initFilter(ApplicationFilterConfig.java:279)
at org.apache.catalina.core.ApplicationFilterConfig.getFilter(ApplicationFilterConfig.java:260)
at org.apache.catalina.core.ApplicationFilterConfig.(ApplicationFilterConfig.java:105)
at org.apache.catalina.core.StandardContext.filterStart(StandardContext.java:4561)
at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5203)
at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:152)
at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1403)
at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1393)
at java.util.concurrent.FutureTask.run(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
Caused by: javax.mail.MessagingException: Could not connect to SMTP host: smtp.exmail.qq.com, port: 465;
nested exception is:
javax.net.ssl.SSLHandshakeException: Received fatal alert: handshake_failure
at com.sun.mail.smtp.SMTPTransport.openServer(SMTPTransport.java:2055)
at com.sun.mail.smtp.SMTPTransport.protocolConnect(SMTPTransport.java:697)
at javax.mail.Service.connect(Service.java:386)
at javax.mail.Service.connect(Service.java:245)
at javax.mail.Service.connect(Service.java:194)
at javax.mail.Transport.send0(Transport.java:253)
at javax.mail.Transport.send(Transport.java:124)
at org.apache.commons.mail.Email.sendMimeMessage(Email.java:1400)
... 19 more
Caused by: javax.net.ssl.SSLHandshakeException: Received fatal alert: handshake_failure
at sun.security.ssl.Alerts.getSSLException(Unknown Source)
at sun.security.ssl.Alerts.getSSLException(Unknown Source)
at sun.security.ssl.SSLSocketImpl.recvAlert(Unknown Source)
at sun.security.ssl.SSLSocketImpl.readRecord(Unknown Source)
at sun.security.ssl.SSLSocketImpl.performInitialHandshake(Unknown Source)
at sun.security.ssl.SSLSocketImpl.startHandshake(Unknown Source)
at sun.security.ssl.SSLSocketImpl.startHandshake(Unknown Source)
at com.sun.mail.util.SocketFetcher.configureSSLSocket(SocketFetcher.java:543)
at com.sun.mail.util.SocketFetcher.createSocket(SocketFetcher.java:348)
at com.sun.mail.util.SocketFetcher.getSocket(SocketFetcher.java:215)
at com.sun.mail.smtp.SMTPTransport.openServer(SMTPTransport.java:2019)
... 26 more
2016-08-17 15:57:33,204 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:140) INFO - Nutz.Mvc[nutz] is up in 1671ms
八月 17, 2016 3:57:33 下午 org.apache.coyote.AbstractProtocol start
信息: Starting ProtocolHandler [http-nio-8080]
八月 17, 2016 3:57:33 下午 org.apache.coyote.AbstractProtocol start
信息: Starting ProtocolHandler [ajp-nio-8009]
八月 17, 2016 3:57:33 下午 org.apache.catalina.startup.Catalina start
信息: Server startup in 3778 ms
2016-08-17 15:57:56,162 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:268) DEBUG - batch acquisition of 0 triggers
2016-08-17 15:58:21,610 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:268) DEBUG - batch acquisition of 0 triggers
2016-08-17 15:58:45,610 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:268) DEBUG - batch acquisition of 0 triggers
2016-08-17 15:59:15,114 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:268) DEBUG - batch acquisition of 0 triggers
2016-08-17 15:59:39,574 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:268) DEBUG - batch acquisition of 0 triggers
2016-08-17 16:00:06,014 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:268) DEBUG - batch acquisition of 0 triggers
2016-08-17 16:00:31,172 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:268) DEBUG - batch acquisition of 0 triggers
2016-08-17 16:00:54,204 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:268) DEBUG - batch acquisition of 0 triggers
