NutQuartzCronJobFactory 创建报错

2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: Server version:        Apache Tomcat/7.0.73
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: Server built:          Nov 7 2016 21:27:23 UTC
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: Server number:         7.0.73.0
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: OS Name:               Windows Vista
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: OS Version:            6.1
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: Architecture:          amd64
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: Java Home:             D:\myEclipse\Common\binary\com.sun.java.jdk.win32.x86_64_1.6.0.013\jre
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: JVM Version:           1.6.0_13-b03
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: JVM Vendor:            Sun Microsystems Inc.
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: CATALINA_BASE:         E:/nutztomcat/tomcat/apache-tomcat-7.0.73
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: CATALINA_HOME:         E:/nutztomcat/tomcat/apache-tomcat-7.0.73
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dcatalina.home=E:/nutztomcat/tomcat/apache-tomcat-7.0.73
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dcatalina.base=E:/nutztomcat/tomcat/apache-tomcat-7.0.73
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Djava.endorsed.dirs=E:/nutztomcat/tomcat/apache-tomcat-7.0.73/common/endorsed
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Djava.io.tmpdir=E:/nutztomcat/tomcat/apache-tomcat-7.0.73/temp
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Djava.library.path=D:/myEclipse/Common/binary/com.sun.java.jdk.win32.x86_64_1.6.0.013/bin;E:/nutztomcat/tomcat/apache-tomcat-7.0.73/bin
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dsun.io.useCanonCaches=false
2018-4-10 22:35:19 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dfile.encoding=UTF-8
2018-4-10 22:35:19 org.apache.catalina.core.AprLifecycleListener lifecycleEvent
信息: Loaded APR based Apache Tomcat Native library 1.2.10 using APR version 1.5.2.
2018-4-10 22:35:19 org.apache.catalina.core.AprLifecycleListener lifecycleEvent
信息: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
2018-4-10 22:35:20 org.apache.catalina.core.AprLifecycleListener initializeSSL
信息: OpenSSL successfully initialized (OpenSSL 1.0.2j  26 Sep 2016)
2018-4-10 22:35:20 org.apache.coyote.AbstractProtocol init
信息: Initializing ProtocolHandler ["http-apr-8080"]
2018-4-10 22:35:20 org.apache.coyote.AbstractProtocol init
信息: Initializing ProtocolHandler ["ajp-apr-8009"]
2018-4-10 22:35:20 org.apache.catalina.startup.Catalina load
信息: Initialization processed in 976 ms
2018-4-10 22:35:20 org.apache.catalina.core.StandardService startInternal
信息: Starting service Catalina
2018-4-10 22:35:20 org.apache.catalina.core.StandardEngine startInternal
信息: Starting Servlet Engine: Apache Tomcat/7.0.73
2018-4-10 22:35:20 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\docs
2018-4-10 22:35:20 org.apache.tomcat.websocket.server.WsSci onStartup
信息: JSR 356 WebSocket (Java WebSocket 1.1) support is not available when running on Java 6. To suppress this message, run Tomcat on Java 7, remove the WebSocket JARs from $CATALINA_HOME/lib or add the WebSocket JARs to the tomcat.util.scan.DefaultJarScanner.jarsToSkip property in $CATALINA_BASE/conf/catalina.properties. Note that the deprecated Tomcat 7 WebSocket API will be available. 
2018-4-10 22:35:20 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\docs has finished in 382 ms
2018-4-10 22:35:20 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\examples
2018-4-10 22:35:21 org.apache.catalina.core.ApplicationContext log
信息: ContextListener: contextInitialized()
2018-4-10 22:35:21 org.apache.catalina.core.ApplicationContext log
信息: SessionListener: contextInitialized()
2018-4-10 22:35:21 org.apache.catalina.core.ApplicationContext log
信息: ContextListener: attributeAdded('org.apache.jasper.compiler.TldLocationsCache', 'org.apache.jasper.compiler.TldLocationsCache@5edf2d38')
2018-4-10 22:35:21 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\examples has finished in 387 ms
2018-4-10 22:35:21 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\host-manager
2018-4-10 22:35:21 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\host-manager has finished in 62 ms
2018-4-10 22:35:21 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\manager
2018-4-10 22:35:21 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\manager has finished in 58 ms
2018-4-10 22:35:21 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook
2018-4-10 22:35:22 org.apache.catalina.startup.TaglibUriRule body
信息: TLD skipped. URI: http://java.sun.com/jstl/core is already defined
2018-4-10 22:35:22 org.apache.catalina.startup.TaglibUriRule body
信息: TLD skipped. URI: http://java.sun.com/jstl/fmt is already defined
2018-4-10 22:35:22 org.apache.catalina.startup.TaglibUriRule body
信息: TLD skipped. URI: http://java.sun.com/jstl/sql is already defined
2018-4-10 22:35:22 org.apache.catalina.startup.TaglibUriRule body
信息: TLD skipped. URI: http://java.sun.com/jstl/xml is already defined
2018-4-10 22:35:22 org.apache.catalina.startup.TldConfig execute
信息: 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.
2018-4-10 22:35:22 org.apache.catalina.core.ApplicationContext log
信息: Initializing Shiro environment
2018-04-10 22:35:22,680 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:128) INFO  - Starting Shiro environment initialization.
2018-04-10 22:35:22,698 org.apache.shiro.web.env.IniWebEnvironment.init(IniWebEnvironment.java:76) DEBUG - Checking any specified config locations.
2018-04-10 22:35:22,698 org.apache.shiro.web.env.IniWebEnvironment.init(IniWebEnvironment.java:81) DEBUG - No INI instance or config locations specified.  Trying default config locations.
2018-04-10 22:35:22,705 org.apache.shiro.io.ResourceUtils.loadFromClassPath(ResourceUtils.java:159) DEBUG - Opening resource from class path [shiro.ini]
2018-04-10 22:35:22,710 org.apache.shiro.config.Ini.load(Ini.java:351) DEBUG - Parsing [main]
2018-04-10 22:35:22,710 org.apache.shiro.config.Ini.load(Ini.java:351) DEBUG - Parsing [urls]
2018-04-10 22:35:22,713 org.apache.shiro.web.env.IniWebEnvironment.getDefaultIni(IniWebEnvironment.java:136) DEBUG - Discovered non-empty INI configuration at location 'classpath:shiro.ini'.  Using for configuration.
2018-04-10 22:35:22,718 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:122) DEBUG - Creating instance from Ini [sections=urls]
2018-04-10 22:35:22,980 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:122) DEBUG - Creating instance from Ini [sections=urls]
2018-04-10 22:35:22,983 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/*] from String definition [anon]
2018-04-10 22:35:22,983 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/*] to filter [anon] with config [null]
2018-04-10 22:35:22,984 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]
2018-04-10 22:35:22,984 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:141) INFO  - Shiro environment initialized in 301 ms.
2018-04-10 22:35:23,005 org.nutz.mvc.NutFilter._init(NutFilter.java:85) INFO  - NutFilter[nutz] starting ...
2018-04-10 22:35:23,032 org.nutz.resource.Scans.printLocations(Scans.java:521) DEBUG - Locations count=5 time use 16ms
2018-04-10 22:35:23,149 org.nutz.resource.Scans.printLocations(Scans.java:521) DEBUG - Locations count=29 time use 116ms
2018-04-10 22:35:23,237 org.nutz.castor.Castors.reload(Castors.java:115) DEBUG - Using 95 castor for Castors
2018-04-10 22:35:23,263 org.nutz.mvc.config.AbstractNutConfig.createLoading(AbstractNutConfig.java:59) DEBUG - Loading by class org.nutz.mvc.impl.NutLoading
2018-04-10 22:35:23,265 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:55) INFO  - Nutz Version : 1.r.62 
2018-04-10 22:35:23,266 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:56) INFO  - Nutz.Mvc[nutz] is initializing ...
2018-04-10 22:35:23,266 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:60) DEBUG - Web Container Information:
2018-04-10 22:35:23,267 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:61) DEBUG -  - Default Charset : UTF-8
2018-04-10 22:35:23,267 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:62) DEBUG -  - Current . path  : E:\nutztomcat\tomcat\apache-tomcat-7.0.73\bin\.
2018-04-10 22:35:23,267 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:63) DEBUG -  - Java Version    : 1.6.0_13
2018-04-10 22:35:23,268 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:64) DEBUG -  - File separator  : \
2018-04-10 22:35:23,268 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:65) DEBUG -  - Timezone        : Asia/Shanghai
2018-04-10 22:35:23,268 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:66) DEBUG -  - OS              : Windows Vista amd64
2018-04-10 22:35:23,268 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:67) DEBUG -  - ServerInfo      : Apache Tomcat/7.0.73
2018-04-10 22:35:23,268 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:68) DEBUG -  - Servlet API     : 3.0
2018-04-10 22:35:23,268 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:73) DEBUG -  - ContextPath     : /nutzbook
2018-04-10 22:35:23,268 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:74) DEBUG -  - context.tempdir : E:\nutztomcat\tomcat\apache-tomcat-7.0.73\work\Catalina\localhost\nutzbook
2018-04-10 22:35:23,269 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:75) DEBUG -  - MainModule      : net.wendal.nutzbook.MainModule
2018-04-10 22:35:23,269 org.nutz.mvc.impl.NutLoading.createContext(NutLoading.java:235) DEBUG - >> app.root = E:/nutztomcat/tomcat/apache-tomcat-7.0.73/webapps/nutzbook
2018-04-10 22:35:23,272 org.nutz.mvc.impl.NutLoading.createIoc(NutLoading.java:376) DEBUG - @IocBy(type=org.nutz.mvc.ioc.provider.ComboIocProvider, args=["*js", "ioc/", "*anno", "net.wendal.nutzbook", "*tx", "*quartz"],init=[])
2018-04-10 22:35:23,355 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 2 resource by src( ioc/ ) , regex( ^(.+[.])(js|json)$ )
2018-04-10 22:35:23,355 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:49) DEBUG - loading [dao.js]
2018-04-10 22:35:23,364 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:49) DEBUG - loading [mail.js]
2018-04-10 22:35:23,365 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:57) DEBUG - Loaded 5 bean define from path=[ioc/] --> [htmlEmail, dataSource, dao, conf, emailAuthenticator]
2018-04-10 22:35:23,387 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 16 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-04-10 22:35:23,437 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.CaptchaModule
2018-04-10 22:35:23,450 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserModule
2018-04-10 22:35:23,460 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserProfileModule
2018-04-10 22:35:23,476 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.quartz.job.CleanNonActiveUserJob
2018-04-10 22:35:23,479 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.quartz.NutQuartzCronJobFactory
2018-04-10 22:35:23,481 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.quartz.NutQuartzJobFactory
2018-04-10 22:35:23,485 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.service.EmailServiceImpl
2018-04-10 22:35:23,486 org.nutz.ioc.loader.annotation.AnnotationIocLoader.<init>(AnnotationIocLoader.java:52) INFO  - Found 7 classes in 1 base-packages!
beans = ["captchaModule", "cleanNonActiveUserJob", "userProfileModule", "nutQuartzCronJobFactory", "emailService", "userModule", "nutQuartzJobFactory"]
2018-04-10 22:35:23,495 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:40) DEBUG - Loaded 5 bean define from reader --
[txREPEATABLE_READ, txREAD_COMMITTED, txNONE, txSERIALIZABLE, txREAD_UNCOMMITTED]
2018-04-10 22:35:23,498 org.nutz.ioc.loader.combo.ComboIocLoader.createIocLoader(ComboIocLoader.java:110) DEBUG - found quartz -- org.nutz.integration.quartz.QuartzIocLoader
2018-04-10 22:35:23,503 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:57) DEBUG - Loaded 0 bean define from path=[] --> []
2018-04-10 22:35:23,509 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:98) INFO  - NutIoc init begin ...
2018-04-10 22:35:23,512 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:115) INFO  - ... NutIoc init complete
2018-04-10 22:35:23,516 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:159) INFO  - Build URL mapping by org.nutz.mvc.impl.UrlMappingImpl ...
2018-04-10 22:35:23,519 org.nutz.mvc.impl.NutLoading.createViewMakers(NutLoading.java:366) DEBUG - @Views(DefaultViewMaker.class)
2018-04-10 22:35:23,532 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( mvc/nutzbook-mvc-chain.js ) , regex( ^(.+[.])(js|json)$ )
2018-04-10 22:35:23,542 org.nutz.mvc.impl.chainconfig.JsonActionChainMakerConfiguretion.<init>(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.integration.shiro.NutShiroProcessor", "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"
   }
}
2018-04-10 22:35:23,542 org.nutz.mvc.impl.NutLoading.createChainMaker(NutLoading.java:263) DEBUG - @ChainBy(org.nutz.mvc.impl.NutActionChainMaker)
2018-04-10 22:35:23,549 org.nutz.mvc.impl.Loadings.scanModules(Loadings.java:154) DEBUG - module class location 'file:/E:/nutztomcat/tomcat/apache-tomcat-7.0.73/webapps/nutzbook/WEB-INF/classes/'
2018-04-10 22:35:23,550 org.nutz.mvc.impl.Loadings.scanModuleInPackage(Loadings.java:184) DEBUG -  > scan 'net.wendal.nutzbook'
2018-04-10 22:35:23,559 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 16 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-04-10 22:35:23,561 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:199) DEBUG -    >> add 'net.wendal.nutzbook.module.CaptchaModule'
2018-04-10 22:35:23,562 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:199) DEBUG -    >> add 'net.wendal.nutzbook.module.UserModule'
2018-04-10 22:35:23,562 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:199) DEBUG -    >> add 'net.wendal.nutzbook.module.UserProfileModule'
2018-04-10 22:35:23,692 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/get'         >> (UserProfileModule.java:52).get       : UserProfile | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,694 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/'            >> (UserProfileModule.java:142).index    : UserProfile | @Ok(jsp:jsp.user.profile) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,705 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/update'      >> (UserProfileModule.java:67).update    : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,724 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) INFO  - Init file-pool by: E:/nutztomcat/tomcat/apache-tomcat-7.0.73/webapps/nutzbook/WEB-INF/tmp/user_avatar [20000]
2018-04-10 22:35:23,728 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) DEBUG - file-pool.home: 'E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\tmp\user_avatar'
2018-04-10 22:35:23,730 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) INFO  - file-pool.cursor: 0
2018-04-10 22:35:23,741 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:100).uploadAvatar : void       | @Ok(>>:/user/profile) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,747 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:130).readAvatar : Object     | @Ok(raw:jpg) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,748 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/active/mail' >> (UserProfileModule.java:151).activeMail : Object     | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,751 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/active/mail' >> (UserProfileModule.java:178).activeMailCallback : String     | @Ok(raw  ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,830 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/add'                 >> (UserModule.java:69).add              : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,831 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/count'               >> (UserModule.java:38).count            : int        | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,833 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/delete'              >> (UserModule.java:97).delete           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,840 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/query'               >> (UserModule.java:107).query           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,841 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/index'               >> (UserModule.java:119).index           : void       | @Ok(jsp:jsp.user.list) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,843 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/update'              >> (UserModule.java:82).update           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,844 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/login'               >> (UserModule.java:48).login            : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/logout'              >> (UserModule.java:64).logout           : void       | @Ok(>>:/ ) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,847 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/captcha/next'             >> (CaptchaModule.java:23).next          : BufferedImage | @Ok(raw:png) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 22:35:23,847 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:217) INFO  - Found 16 module methods
2018-04-10 22:35:23,847 org.nutz.mvc.impl.NutLoading.evalLocalization(NutLoading.java:303) DEBUG - Localization: org.nutz.mvc.impl.NutMessageLoader('msg/')  dft<zh-CN>
2018-04-10 22:35:23,851 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( msg/ ) , regex( ^.+[.]properties$ )
2018-04-10 22:35:23,851 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:27) DEBUG - Load Messages in 1 resource : [[File[E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\classes\msg\zh-CN\user.properties]]]
2018-04-10 22:35:23,853 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:95) DEBUG - Message Loaded, size = 2
2018-04-10 22:35:23,853 org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:271) INFO  - Setup application...
2018-04-10 22:35:23,856 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dao'<interface org.nutz.dao.Dao>
2018-04-10 22:35:23,858 org.nutz.ioc.aop.SimpleAopMaker.<init>(SimpleAopMaker.java:79) DEBUG - Load AopConfigure for anno=org.nutz.ioc.aop.Aop by type=org.nutz.ioc.aop.config.impl.AnnotationAopConfigration
2018-04-10 22:35:23,858 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dao
2018-04-10 22:35:23,859 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dao
2018-04-10 22:35:23,863 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(dao) in JsonLoader(paths=[ioc/])
2018-04-10 22:35:23,863 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dao'<interface org.nutz.dao.Dao>
2018-04-10 22:35:23,891 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.dao.impl.NutDao without AOP
2018-04-10 22:35:23,892 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dao' to [app] 
2018-04-10 22:35:23,895 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2018-04-10 22:35:23,895 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dataSource
2018-04-10 22:35:23,895 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dataSource
2018-04-10 22:35:23,907 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(dataSource) in JsonLoader(paths=[ioc/])
2018-04-10 22:35:23,912 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dataSource'<>
2018-04-10 22:35:23,932 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class com.alibaba.druid.pool.DruidDataSource without AOP
2018-04-10 22:35:23,933 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dataSource' to [app] 
2018-04-10 22:35:23,935 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-04-10 22:35:23,935 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=conf
2018-04-10 22:35:23,935 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=conf
2018-04-10 22:35:23,946 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(conf) in JsonLoader(paths=[ioc/])
2018-04-10 22:35:23,946 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'conf'<>
2018-04-10 22:35:23,947 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.ioc.impl.PropertiesProxy without AOP
2018-04-10 22:35:23,947 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'conf' to [app] 
2018-04-10 22:35:23,992 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 3 resource by src( custom/ ) , regex( ^.+[.]properties$ )
2018-04-10 22:35:23,993 org.nutz.ioc.impl.PropertiesProxy.setPaths(PropertiesProxy.java:113) DEBUG - load properties from File[E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\classes\custom\cron.properties]
2018-04-10 22:35:23,993 org.nutz.ioc.impl.PropertiesProxy.setPaths(PropertiesProxy.java:113) DEBUG - load properties from File[E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\classes\custom\db.properties]
2018-04-10 22:35:23,994 org.nutz.ioc.impl.PropertiesProxy.setPaths(PropertiesProxy.java:113) DEBUG - load properties from File[E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\classes\custom\mail.properties]
2018-04-10 22:35:24,298 com.alibaba.druid.pool.DruidDataSource.init(DruidDataSource.java:715) INFO  - {dataSource-1} inited
2018-04-10 22:35:24,299 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2018-04-10 22:35:24,515 org.nutz.dao.jdbc.Jdbcs.<clinit>(Jdbcs.java:90) DEBUG - Jdbcs init complete
2018-04-10 22:35:24,515 org.nutz.dao.jdbc.Jdbcs.getExpert(Jdbcs.java:103) INFO  - Get Connection from DataSource for JdbcExpert, if you lock at here, check your database server and configure
2018-04-10 22:35:24,713 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:188) DEBUG - select expert : org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert
2018-04-10 22:35:24,725 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:199) DEBUG - JDBC Driver --> mysql-connector-java-5.1.37 ( Revision: 09940f05b4c98150f352e787a2549f11a2e9da93 )
2018-04-10 22:35:24,725 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:200) DEBUG - JDBC Name   --> MySQL Connector Java
2018-04-10 22:35:24,725 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:202) DEBUG - JDBC URL    --> jdbc:mysql://127.0.0.1:3306/nutzbook
2018-04-10 22:35:24,862 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_client=utf8
2018-04-10 22:35:24,862 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_connection=utf8
2018-04-10 22:35:24,862 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_database=utf8
2018-04-10 22:35:24,863 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_filesystem=binary
2018-04-10 22:35:24,863 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_results=
2018-04-10 22:35:24,863 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_server=utf8
2018-04-10 22:35:24,864 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_system=utf8
2018-04-10 22:35:24,864 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_sets_dir=D:\mysql\share\charsets\
2018-04-10 22:35:24,866 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:213) DEBUG - Mysql : binlog_format=STATEMENT
2018-04-10 22:35:24,867 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:220) DEBUG - Mysql : database=nutzbook
2018-04-10 22:35:24,867 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:226) DEBUG - Mysql : user=root@localhost
2018-04-10 22:35:24,917 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:221) DEBUG - Database info --> MYSQL:[MySQL - 5.5.56]
2018-04-10 22:35:24,931 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 16 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-04-10 22:35:25,023 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT COUNT(*) FROM t_user 
2018-04-10 22:35:25,028 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'nutQuartzCronJobFactory'<class org.nutz.integration.quartz.NutQuartzCronJobFactory>
2018-04-10 22:35:25,028 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=nutQuartzCronJobFactory
2018-04-10 22:35:25,029 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(nutQuartzCronJobFactory) in AnnotationIocLoader(packages=[net.wendal.nutzbook])
2018-04-10 22:35:25,029 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'nutQuartzCronJobFactory'<class org.nutz.integration.quartz.NutQuartzCronJobFactory>
2018-04-10 22:35:25,029 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class net.wendal.nutzbook.quartz.NutQuartzCronJobFactory without AOP
2018-04-10 22:35:25,029 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'nutQuartzCronJobFactory' to [app] 
2018-04-10 22:35:25,034 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<class org.nutz.ioc.impl.PropertiesProxy>
2018-04-10 22:35:25,035 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'scheduler'<interface org.quartz.Scheduler>
2018-04-10 22:35:25,035 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=scheduler
2018-04-10 22:35:25,035 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=scheduler
2018-04-10 22:35:25,036 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(scheduler) in QuartzIocLoader@229827661
2018-04-10 22:35:25,036 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'scheduler'<interface org.quartz.Scheduler>
2018-04-10 22:35:25,037 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'scheduler' to [app] 
2018-04-10 22:35:25,122 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1141) INFO  - Using default implementation for ThreadExecutor
2018-04-10 22:35:25,148 org.quartz.core.SchedulerSignalerImpl.<init>(SchedulerSignalerImpl.java:61) INFO  - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2018-04-10 22:35:25,149 org.quartz.core.QuartzScheduler.<init>(QuartzScheduler.java:238) INFO  - Quartz Scheduler v.2.0.2 created.
2018-04-10 22:35:25,151 org.quartz.simpl.RAMJobStore.initialize(RAMJobStore.java:154) INFO  - RAMJobStore initialized.
2018-04-10 22:35:25,152 org.quartz.core.QuartzScheduler.initialize(QuartzScheduler.java:259) INFO  - Scheduler meta-data: Quartz Scheduler (v2.0.2) '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.

2018-04-10 22:35:25,152 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1288) INFO  - Quartz scheduler 'NutzbookScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
2018-04-10 22:35:25,152 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1292) INFO  - Quartz scheduler version: 2.0.2
2018-04-10 22:35:25,153 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'jobFactory'<>
2018-04-10 22:35:25,153 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=jobFactory
2018-04-10 22:35:25,153 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=jobFactory
2018-04-10 22:35:25,155 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(jobFactory) in QuartzIocLoader@229827661
2018-04-10 22:35:25,155 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'jobFactory'<>
2018-04-10 22:35:25,155 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.integration.quartz.NutQuartzJobFactory without AOP
2018-04-10 22:35:25,156 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'jobFactory' to [app] 
2018-04-10 22:35:25,204 org.quartz.core.QuartzScheduler.setJobFactory(QuartzScheduler.java:2233) INFO  - JobFactory set to: org.nutz.integration.quartz.NutQuartzJobFactory@1ae637f0
2018-04-10 22:35:25,204 org.quartz.core.QuartzScheduler.start(QuartzScheduler.java:523) INFO  - Scheduler NutzbookScheduler_$_NON_CLUSTERED started.
2018-04-10 22:35:25,205 net.wendal.nutzbook.quartz.NutQuartzCronJobFactory.init(NutQuartzCronJobFactory.java:33) DEBUG - job define name=net.wendal.nutzbook.quartz.job.CleanNonActiveUserJob cron=0 0/2 * * * ?
2018-04-10 22:35:25,208 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:268) DEBUG - batch acquisition of 0 triggers
2018-04-10 22:35:25,227 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:268) DEBUG - batch acquisition of 0 triggers
2018-04-10 22:35:25,227 net.wendal.nutzbook.quartz.NutQuartzCronJobFactory.init(NutQuartzCronJobFactory.java:33) DEBUG - job define name=pkgs cron=net.wendal.nutzbook.quartz.job
2018-04-10 22:35:25,228 org.nutz.ioc.impl.ScopeContext.remove(ScopeContext.java:84) DEBUG - Remove object 'nutQuartzCronJobFactory' from [app] 
2018-04-10 22:35:25,231 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:125) ERROR - Error happend during start serivce!
org.nutz.ioc.IocException: IocBean[nutQuartzCronJobFactory] 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.ioc.impl.NutIoc.get(NutIoc.java:146)
	at net.wendal.nutzbook.MainSetup.init(MainSetup.java:33)
	at org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:274)
	at org.nutz.mvc.impl.NutLoading.load(NutLoading.java:121)
	at org.nutz.mvc.ActionHandler.<init>(ActionHandler.java:19)
	at org.nutz.mvc.NutFilter._init(NutFilter.java:91)
	at org.nutz.mvc.NutFilter.init(NutFilter.java:69)
	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.<init>(ApplicationFilterConfig.java:105)
	at org.apache.catalina.core.StandardContext.filterStart(StandardContext.java:4958)
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5652)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:145)
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:899)
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:875)
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652)
	at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1260)
	at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:2002)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
	at java.lang.Thread.run(Thread.java:619)
Caused by: java.lang.RuntimeException: java.lang.ClassNotFoundException: net.wendal.nutzbook.quartz.job.pkgs
	at org.nutz.lang.Lang.wrapThrow(Lang.java:184)
	at org.nutz.lang.Lang.wrapThrow(Lang.java:183)
	at org.nutz.ioc.trigger.MethodEventTrigger.trigger(MethodEventTrigger.java:21)
	at org.nutz.ioc.weaver.DefaultWeaver.onCreate(DefaultWeaver.java:72)
	at org.nutz.ioc.impl.ObjectMakerImpl.make(ObjectMakerImpl.java:138)
	... 26 more
Caused by: java.lang.ClassNotFoundException: net.wendal.nutzbook.quartz.job.pkgs
	at org.apache.catalina.loader.WebappClassLoaderBase.loadClass(WebappClassLoaderBase.java:1892)
	at org.apache.catalina.loader.WebappClassLoaderBase.loadClass(WebappClassLoaderBase.java:1735)
	at java.lang.ClassLoader.loadClassInternal(ClassLoader.java:320)
	at java.lang.Class.forName0(Native Method)
	at java.lang.Class.forName(Class.java:169)
	at net.wendal.nutzbook.quartz.NutQuartzCronJobFactory.init(NutQuartzCronJobFactory.java:38)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at org.nutz.ioc.trigger.MethodEventTrigger.trigger(MethodEventTrigger.java:18)
	... 28 more
2018-04-10 22:35:25,234 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:127) ERROR - try to depose ioc
2018-04-10 22:35:25,238 org.nutz.ioc.impl.NutIoc.depose(NutIoc.java:255) INFO  - org.nutz.ioc.impl.NutIoc@1852151826 is closing. startup date [18-04-10 22:35:23.509]
2018-04-10 22:35:25,238 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'jobFactory' ...
2018-04-10 22:35:25,238 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'scheduler' ...
2018-04-10 22:35:25,238 org.quartz.core.QuartzScheduler.shutdown(QuartzScheduler.java:639) INFO  - Scheduler NutzbookScheduler_$_NON_CLUSTERED shutting down.
2018-04-10 22:35:25,239 org.quartz.core.QuartzScheduler.standby(QuartzScheduler.java:560) INFO  - Scheduler NutzbookScheduler_$_NON_CLUSTERED paused.
2018-04-10 22:35:25,239 org.quartz.core.QuartzScheduler.shutdown(QuartzScheduler.java:711) INFO  - Scheduler NutzbookScheduler_$_NON_CLUSTERED shutdown complete.
2018-04-10 22:35:25,239 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'conf' ...
2018-04-10 22:35:25,239 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'dao' ...
2018-04-10 22:35:25,240 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:113) DEBUG - Depose object 'dataSource' ...
2018-04-10 22:35:25,246 com.alibaba.druid.pool.DruidDataSource.close(DruidDataSource.java:1444) INFO  - {dataSource-1} closed
2018-04-10 22:35:25,247 org.nutz.ioc.impl.NutIoc.depose(NutIoc.java:262) INFO  - org.nutz.ioc.impl.NutIoc@1852151826 is deposed. startup date [18-04-10 22:35:23.509]
2018-4-10 22:35:25 org.apache.catalina.core.StandardContext filterStart
严重: Exception starting filter nutz
org.nutz.mvc.LoadingException: org.nutz.ioc.IocException: IocBean[nutQuartzCronJobFactory] throw Exception when creating
	at org.nutz.mvc.LoadingException$FC$9a86caf9ff5d3b587784fcdf555a4a7d.invoke(LoadingException.java)
	at org.nutz.lang.born.AbstractConstructorBorning.call(AbstractConstructorBorning.java:25)
	at org.nutz.lang.born.ConstructorBorning.born(ConstructorBorning.java:19)
	at org.nutz.lang.born.BornContext.doBorn(BornContext.java:60)
	at org.nutz.lang.Mirror.born(Mirror.java:990)
	at org.nutz.lang.Lang.wrapThrow(Lang.java:200)
	at org.nutz.mvc.impl.NutLoading.load(NutLoading.java:135)
	at org.nutz.mvc.ActionHandler.<init>(ActionHandler.java:19)
	at org.nutz.mvc.NutFilter._init(NutFilter.java:91)
	at org.nutz.mvc.NutFilter.init(NutFilter.java:69)
	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.<init>(ApplicationFilterConfig.java:105)
	at org.apache.catalina.core.StandardContext.filterStart(StandardContext.java:4958)
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5652)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:145)
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:899)
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:875)
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652)
	at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1260)
	at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:2002)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
	at java.lang.Thread.run(Thread.java:619)
Caused by: org.nutz.ioc.IocException: IocBean[nutQuartzCronJobFactory] 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.ioc.impl.NutIoc.get(NutIoc.java:146)
	at net.wendal.nutzbook.MainSetup.init(MainSetup.java:33)
	at org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:274)
	at org.nutz.mvc.impl.NutLoading.load(NutLoading.java:121)
	... 20 more
Caused by: java.lang.RuntimeException: java.lang.ClassNotFoundException: net.wendal.nutzbook.quartz.job.pkgs
	at org.nutz.lang.Lang.wrapThrow(Lang.java:184)
	at org.nutz.lang.Lang.wrapThrow(Lang.java:183)
	at org.nutz.ioc.trigger.MethodEventTrigger.trigger(MethodEventTrigger.java:21)
	at org.nutz.ioc.weaver.DefaultWeaver.onCreate(DefaultWeaver.java:72)
	at org.nutz.ioc.impl.ObjectMakerImpl.make(ObjectMakerImpl.java:138)
	... 26 more
Caused by: java.lang.ClassNotFoundException: net.wendal.nutzbook.quartz.job.pkgs
	at org.apache.catalina.loader.WebappClassLoaderBase.loadClass(WebappClassLoaderBase.java:1892)
	at org.apache.catalina.loader.WebappClassLoaderBase.loadClass(WebappClassLoaderBase.java:1735)
	at java.lang.ClassLoader.loadClassInternal(ClassLoader.java:320)
	at java.lang.Class.forName0(Native Method)
	at java.lang.Class.forName(Class.java:169)
	at net.wendal.nutzbook.quartz.NutQuartzCronJobFactory.init(NutQuartzCronJobFactory.java:38)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at org.nutz.ioc.trigger.MethodEventTrigger.trigger(MethodEventTrigger.java:18)
	... 28 more
2018-4-10 22:35:25 org.apache.catalina.core.StandardContext startInternal
严重: One or more Filters failed to start. Full details will be found in the appropriate container log file
2018-4-10 22:35:25 org.apache.catalina.core.StandardContext startInternal
严重: Context [/nutzbook] startup failed due to previous errors
2018-4-10 22:35:25 org.apache.catalina.core.ApplicationContext log
信息: Cleaning up Shiro Environment
2018-4-10 22:35:25 org.apache.catalina.loader.WebappClassLoaderBase clearReferencesJdbc
严重: The web application [/nutzbook] registered the JDBC driver [com.alibaba.druid.proxy.DruidDriver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
2018-4-10 22:35:25 org.apache.catalina.loader.WebappClassLoaderBase clearReferencesJdbc
严重: The web application [/nutzbook] registered the JDBC driver [com.mysql.jdbc.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
2018-4-10 22:35:25 org.apache.catalina.loader.WebappClassLoaderBase clearReferencesThreads
严重: The web application [/nutzbook] appears to have started a thread named [Abandoned connection cleanup thread] but has failed to stop it. This is very likely to create a memory leak.
2018-4-10 22:35:25 org.apache.catalina.loader.WebappClassLoaderBase clearReferencesThreads
严重: The web application [/nutzbook] appears to have started a thread named [NutzbookScheduler_Worker-1] but has failed to stop it. This is very likely to create a memory leak.
2018-4-10 22:35:25 org.apache.catalina.loader.WebappClassLoaderBase clearReferencesThreads
严重: The web application [/nutzbook] appears to have started a thread named [NutzbookScheduler_Worker-2] but has failed to stop it. This is very likely to create a memory leak.
2018-4-10 22:35:25 org.apache.catalina.loader.WebappClassLoaderBase clearReferencesThreads
严重: The web application [/nutzbook] appears to have started a thread named [NutzbookScheduler_Worker-3] but has failed to stop it. This is very likely to create a memory leak.
2018-4-10 22:35:25 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook has finished in 3,956 ms
2018-4-10 22:35:25 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\ROOT
2018-4-10 22:35:25 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\ROOT has finished in 70 ms
2018-4-10 22:35:25 org.apache.coyote.AbstractProtocol start
信息: Starting ProtocolHandler ["http-apr-8080"]
2018-4-10 22:35:25 org.apache.coyote.AbstractProtocol start
信息: Starting ProtocolHandler ["ajp-apr-8009"]
2018-4-10 22:35:25 org.apache.catalina.startup.Catalina start
信息: Server startup in 4971 ms
2018-04-10 22:35:25,629 org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:595) DEBUG - WorkerThread is shut down.
2018-04-10 22:35:25,631 org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:595) DEBUG - WorkerThread is shut down.
2018-04-10 22:35:25,629 org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:595) DEBUG - WorkerThread is shut down.

NutQuartzCronJobFactory.java

package net.wendal.nutzbook.quartz;

import org.nutz.ioc.impl.PropertiesProxy;
import org.nutz.ioc.loader.annotation.Inject;
import org.nutz.ioc.loader.annotation.IocBean;
import org.nutz.log.Log;
import org.nutz.log.Logs;
import org.quartz.CronScheduleBuilder;
import org.quartz.CronTrigger;
import org.quartz.Job;
import org.quartz.JobBuilder;
import org.quartz.JobDetail;
import org.quartz.Scheduler;
import org.quartz.TriggerBuilder;

@IocBean(create="init")
public class NutQuartzCronJobFactory  {
	
	private static final Log log = Logs.get();

	@Inject protected PropertiesProxy conf;
	
	@Inject protected Scheduler scheduler;
	
	@SuppressWarnings("unchecked")
	public void init() throws Exception {
		String prefix = "cron.";
		for (String key : conf.getKeys()) {
			if (key.length() < prefix.length()+1 || !key.startsWith(prefix))
				continue;
			String name = key.substring(prefix.length());
			String cron = conf.get(key);
			log.debugf("job define name=%s cron=%s", name, cron);
			Class<?> klass = null;
			if (name.contains(".")) {
				klass = Class.forName(name);
			} else {
				klass = Class.forName(getClass().getPackage().getName() + ".job." + name);
			}
			JobDetail job = JobBuilder.newJob((Class<? extends Job>) klass).build();
			CronTrigger trigger = TriggerBuilder.newTrigger().withIdentity(name)
				    .withSchedule(CronScheduleBuilder.cronSchedule(cron))
				    .build();
			scheduler.scheduleJob(job, trigger);
		}
	}

}

MainModule.java

package net.wendal.nutzbook;

import org.nutz.mvc.annotation.ChainBy;
import org.nutz.mvc.annotation.Fail;
import org.nutz.mvc.annotation.IocBy;
import org.nutz.mvc.annotation.Localization;
import org.nutz.mvc.annotation.Modules;
import org.nutz.mvc.annotation.Ok;
import org.nutz.mvc.annotation.SetupBy;
import org.nutz.mvc.ioc.provider.ComboIocProvider;


@Ok("json:full")
@Fail("jsp:jsp.500")
@ChainBy(args="mvc/nutzbook-mvc-chain.js")
@Localization(value="msg/", defaultLocalizationKey="zh-CN")
@SetupBy(value=MainSetup.class)

@IocBy(type=ComboIocProvider.class, args={"*js", "ioc/",
	"*anno", "net.wendal.nutzbook",
	"*tx", // 事务拦截 aop
"*quartz"}) // 异步执行aop
@Modules(scanPackage=true)
public class MainModule {
}

quartz.properties

org.quartz.scheduler.instanceName = NutzbookScheduler
org.quartz.threadPool.threadCount = 3
org.quartz.jobStore.class = org.quartz.simpl.RAMJobStore
org.quartz.scheduler.skipUpdateCheck=true

quartz.js

var ioc = {
		scheduler : {
			type : "org.quartz.Scheduler",
			factory: "org.quartz.impl.StdSchedulerFactory#getDefaultScheduler",
			events : {
				create : "start",
				depose : "shutdown",
			},
			fields : {
				jobFactory : {refer:"nutQuartzJobFactory"}
			}
		}
};

看了下社区的帖子，帖子说把quartz.js删掉。我试了没用，还是报同样的错误。

已经过去ue(qq_2b4e731b) 1楼•2448天前

@wendal
还有报错的NutQuartzCronJobFactory类，还有一个类在入门手册里好像没有，我是看了您的源码才知道的。

package net.wendal.nutzbook.quartz;

import org.nutz.ioc.impl.PropertiesProxy;
import org.nutz.ioc.loader.annotation.Inject;
import org.nutz.ioc.loader.annotation.IocBean;
import org.nutz.log.Log;
import org.nutz.log.Logs;
import org.quartz.CronScheduleBuilder;
import org.quartz.CronTrigger;
import org.quartz.Job;
import org.quartz.JobBuilder;
import org.quartz.JobDetail;
import org.quartz.Scheduler;
import org.quartz.TriggerBuilder;

@IocBean(create="init")
public class NutQuartzCronJobFactory  {
	
	private static final Log log = Logs.get();

	@Inject protected PropertiesProxy conf;
	
	@Inject protected Scheduler scheduler;
	
	@SuppressWarnings("unchecked")
	public void init() throws Exception {
		String prefix = "cron.";
		for (String key : conf.getKeys()) {
			if (key.length() < prefix.length()+1 || !key.startsWith(prefix))
				continue;
			String name = key.substring(prefix.length());
			String cron = conf.get(key);
			log.debugf("job define name=%s cron=%s", name, cron);
			Class<?> klass = null;
			if (name.contains(".")) {
				klass = Class.forName(name);
			} else {
				klass = Class.forName(getClass().getPackage().getName() + ".job." + name);
			}
			JobDetail job = JobBuilder.newJob((Class<? extends Job>) klass).build();
			CronTrigger trigger = TriggerBuilder.newTrigger().withIdentity(name)
				    .withSchedule(CronScheduleBuilder.cronSchedule(cron))
				    .build();
			scheduler.scheduleJob(job, trigger);
		}
	}

}

wendal

2楼•2448天前

NutQuartzCronJobFactory在新版插件里面有，不需要手动添加

你是把cron.properties写错了吧

来自炫酷的 NutzCN

已经过去ue(qq_2b4e731b) 3楼•2448天前

cron.properties

# clean non-active user
cron.net.wendal.nutzbook.quartz.job.CleanNonActiveUserJob=0 0/2 * * * ?
cron.pkgs=net.wendal.nutzbook.quartz.job

新版插件？请问怎么搞

已经过去ue(qq_2b4e731b) 4楼•2448天前

@wendal

已经过去ue(qq_2b4e731b) 5楼•2448天前

谢谢大佬
@wendal

wendal

6楼•2448天前

你先换成1.r.65的quartz插件，并且删掉quartzjs及相关的quartz工厂类，启动，贴日志

来自炫酷的 NutzCN

wendal

7楼•2448天前

新版插件里面有所需的全部东西，除了job类，不需要加其他quartz类才对

来自炫酷的 NutzCN

已经过去ue(qq_2b4e731b) 8楼•2448天前

恩恩，对的我下了最新的插件，就写了job类，但是quartz.js不能删

2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: Server version:        Apache Tomcat/7.0.73
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: Server built:          Nov 7 2016 21:27:23 UTC
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: Server number:         7.0.73.0
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: OS Name:               Windows Vista
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: OS Version:            6.1
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: Architecture:          amd64
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: Java Home:             D:\myEclipse\Common\binary\com.sun.java.jdk.win32.x86_64_1.6.0.013\jre
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: JVM Version:           1.6.0_13-b03
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: JVM Vendor:            Sun Microsystems Inc.
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: CATALINA_BASE:         E:/nutztomcat/tomcat/apache-tomcat-7.0.73
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: CATALINA_HOME:         E:/nutztomcat/tomcat/apache-tomcat-7.0.73
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dcatalina.home=E:/nutztomcat/tomcat/apache-tomcat-7.0.73
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dcatalina.base=E:/nutztomcat/tomcat/apache-tomcat-7.0.73
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Djava.endorsed.dirs=E:/nutztomcat/tomcat/apache-tomcat-7.0.73/common/endorsed
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Djava.io.tmpdir=E:/nutztomcat/tomcat/apache-tomcat-7.0.73/temp
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Djava.library.path=D:/myEclipse/Common/binary/com.sun.java.jdk.win32.x86_64_1.6.0.013/bin;E:/nutztomcat/tomcat/apache-tomcat-7.0.73/bin
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dsun.io.useCanonCaches=false
2018-4-10 23:24:03 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dfile.encoding=UTF-8
2018-4-10 23:24:03 org.apache.catalina.core.AprLifecycleListener lifecycleEvent
信息: Loaded APR based Apache Tomcat Native library 1.2.10 using APR version 1.5.2.
2018-4-10 23:24:03 org.apache.catalina.core.AprLifecycleListener lifecycleEvent
信息: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
2018-4-10 23:24:03 org.apache.catalina.core.AprLifecycleListener initializeSSL
信息: OpenSSL successfully initialized (OpenSSL 1.0.2j  26 Sep 2016)
2018-4-10 23:24:03 org.apache.coyote.AbstractProtocol init
信息: Initializing ProtocolHandler ["http-apr-8080"]
2018-4-10 23:24:03 org.apache.coyote.AbstractProtocol init
信息: Initializing ProtocolHandler ["ajp-apr-8009"]
2018-4-10 23:24:03 org.apache.catalina.startup.Catalina load
信息: Initialization processed in 942 ms
2018-4-10 23:24:03 org.apache.catalina.core.StandardService startInternal
信息: Starting service Catalina
2018-4-10 23:24:03 org.apache.catalina.core.StandardEngine startInternal
信息: Starting Servlet Engine: Apache Tomcat/7.0.73
2018-4-10 23:24:03 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\docs
2018-4-10 23:24:03 org.apache.tomcat.websocket.server.WsSci onStartup
信息: JSR 356 WebSocket (Java WebSocket 1.1) support is not available when running on Java 6. To suppress this message, run Tomcat on Java 7, remove the WebSocket JARs from $CATALINA_HOME/lib or add the WebSocket JARs to the tomcat.util.scan.DefaultJarScanner.jarsToSkip property in $CATALINA_BASE/conf/catalina.properties. Note that the deprecated Tomcat 7 WebSocket API will be available. 
2018-4-10 23:24:04 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\docs has finished in 374 ms
2018-4-10 23:24:04 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\examples
2018-4-10 23:24:04 org.apache.catalina.core.ApplicationContext log
信息: ContextListener: contextInitialized()
2018-4-10 23:24:04 org.apache.catalina.core.ApplicationContext log
信息: SessionListener: contextInitialized()
2018-4-10 23:24:04 org.apache.catalina.core.ApplicationContext log
信息: ContextListener: attributeAdded('org.apache.jasper.compiler.TldLocationsCache', 'org.apache.jasper.compiler.TldLocationsCache@4945f939')
2018-4-10 23:24:04 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\examples has finished in 395 ms
2018-4-10 23:24:04 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\host-manager
2018-4-10 23:24:04 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\host-manager has finished in 63 ms
2018-4-10 23:24:04 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\manager
2018-4-10 23:24:04 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\manager has finished in 59 ms
2018-4-10 23:24:04 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook
2018-4-10 23:24:05 org.apache.catalina.startup.TaglibUriRule body
信息: TLD skipped. URI: http://java.sun.com/jstl/core is already defined
2018-4-10 23:24:05 org.apache.catalina.startup.TaglibUriRule body
信息: TLD skipped. URI: http://java.sun.com/jstl/fmt is already defined
2018-4-10 23:24:05 org.apache.catalina.startup.TaglibUriRule body
信息: TLD skipped. URI: http://java.sun.com/jstl/sql is already defined
2018-4-10 23:24:05 org.apache.catalina.startup.TaglibUriRule body
信息: TLD skipped. URI: http://java.sun.com/jstl/xml is already defined
2018-4-10 23:24:05 org.apache.catalina.startup.TldConfig execute
信息: 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.
2018-4-10 23:24:06 org.apache.catalina.core.ApplicationContext log
信息: Initializing Shiro environment
2018-04-10 23:24:06,023 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:128) INFO  - Starting Shiro environment initialization.
2018-04-10 23:24:06,044 org.apache.shiro.web.env.IniWebEnvironment.init(IniWebEnvironment.java:76) DEBUG - Checking any specified config locations.
2018-04-10 23:24:06,044 org.apache.shiro.web.env.IniWebEnvironment.init(IniWebEnvironment.java:81) DEBUG - No INI instance or config locations specified.  Trying default config locations.
2018-04-10 23:24:06,049 org.apache.shiro.io.ResourceUtils.loadFromClassPath(ResourceUtils.java:159) DEBUG - Opening resource from class path [shiro.ini]
2018-04-10 23:24:06,055 org.apache.shiro.config.Ini.load(Ini.java:351) DEBUG - Parsing [main]
2018-04-10 23:24:06,055 org.apache.shiro.config.Ini.load(Ini.java:351) DEBUG - Parsing [urls]
2018-04-10 23:24:06,057 org.apache.shiro.web.env.IniWebEnvironment.getDefaultIni(IniWebEnvironment.java:136) DEBUG - Discovered non-empty INI configuration at location 'classpath:shiro.ini'.  Using for configuration.
2018-04-10 23:24:06,062 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:122) DEBUG - Creating instance from Ini [sections=urls]
2018-04-10 23:24:06,290 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:122) DEBUG - Creating instance from Ini [sections=urls]
2018-04-10 23:24:06,293 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/*] from String definition [anon]
2018-04-10 23:24:06,293 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/*] to filter [anon] with config [null]
2018-04-10 23:24:06,294 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]
2018-04-10 23:24:06,295 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:141) INFO  - Shiro environment initialized in 269 ms.
2018-04-10 23:24:06,317 org.nutz.mvc.NutFilter._init(NutFilter.java:85) INFO  - NutFilter[nutz] starting ...
2018-04-10 23:24:06,342 org.nutz.resource.Scans.printLocations(Scans.java:521) DEBUG - Locations count=5 time use 15ms
2018-04-10 23:24:06,467 org.nutz.resource.Scans.printLocations(Scans.java:521) DEBUG - Locations count=28 time use 125ms
2018-04-10 23:24:06,556 org.nutz.castor.Castors.reload(Castors.java:115) DEBUG - Using 95 castor for Castors
2018-04-10 23:24:06,584 org.nutz.mvc.config.AbstractNutConfig.createLoading(AbstractNutConfig.java:59) DEBUG - Loading by class org.nutz.mvc.impl.NutLoading
2018-04-10 23:24:06,586 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:55) INFO  - Nutz Version : 1.r.62 
2018-04-10 23:24:06,586 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:56) INFO  - Nutz.Mvc[nutz] is initializing ...
2018-04-10 23:24:06,586 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:60) DEBUG - Web Container Information:
2018-04-10 23:24:06,587 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:61) DEBUG -  - Default Charset : UTF-8
2018-04-10 23:24:06,588 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:62) DEBUG -  - Current . path  : E:\nutztomcat\tomcat\apache-tomcat-7.0.73\bin\.
2018-04-10 23:24:06,588 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:63) DEBUG -  - Java Version    : 1.6.0_13
2018-04-10 23:24:06,588 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:64) DEBUG -  - File separator  : \
2018-04-10 23:24:06,588 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:65) DEBUG -  - Timezone        : Asia/Shanghai
2018-04-10 23:24:06,588 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:66) DEBUG -  - OS              : Windows Vista amd64
2018-04-10 23:24:06,588 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:67) DEBUG -  - ServerInfo      : Apache Tomcat/7.0.73
2018-04-10 23:24:06,588 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:68) DEBUG -  - Servlet API     : 3.0
2018-04-10 23:24:06,589 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:73) DEBUG -  - ContextPath     : /nutzbook
2018-04-10 23:24:06,589 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:74) DEBUG -  - context.tempdir : E:\nutztomcat\tomcat\apache-tomcat-7.0.73\work\Catalina\localhost\nutzbook
2018-04-10 23:24:06,589 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:75) DEBUG -  - MainModule      : net.wendal.nutzbook.MainModule
2018-04-10 23:24:06,589 org.nutz.mvc.impl.NutLoading.createContext(NutLoading.java:235) DEBUG - >> app.root = E:/nutztomcat/tomcat/apache-tomcat-7.0.73/webapps/nutzbook
2018-04-10 23:24:06,593 org.nutz.mvc.impl.NutLoading.createIoc(NutLoading.java:376) DEBUG - @IocBy(type=org.nutz.mvc.ioc.provider.ComboIocProvider, args=["*js", "ioc/", "*anno", "net.wendal.nutzbook", "*tx", "*quartz"],init=[])
2018-04-10 23:24:06,681 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 3 resource by src( ioc/ ) , regex( ^(.+[.])(js|json)$ )
2018-04-10 23:24:06,683 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:49) DEBUG - loading [dao.js]
2018-04-10 23:24:06,692 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:49) DEBUG - loading [mail.js]
2018-04-10 23:24:06,693 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:49) DEBUG - loading [quartz.js]
2018-04-10 23:24:06,694 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:57) DEBUG - Loaded 6 bean define from path=[ioc/] --> [htmlEmail, dataSource, scheduler, dao, conf, emailAuthenticator]
2018-04-10 23:24:06,713 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 14 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-04-10 23:24:06,760 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.CaptchaModule
2018-04-10 23:24:06,772 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserModule
2018-04-10 23:24:06,783 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserProfileModule
2018-04-10 23:24:06,796 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.quartz.job.CleanNonActiveUserJob
2018-04-10 23:24:06,800 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.service.EmailServiceImpl
2018-04-10 23:24:06,801 org.nutz.ioc.loader.annotation.AnnotationIocLoader.<init>(AnnotationIocLoader.java:52) INFO  - Found 5 classes in 1 base-packages!
beans = ["captchaModule", "cleanNonActiveUserJob", "userProfileModule", "emailService", "userModule"]
2018-04-10 23:24:06,808 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:40) DEBUG - Loaded 5 bean define from reader --
[txREPEATABLE_READ, txREAD_COMMITTED, txNONE, txSERIALIZABLE, txREAD_UNCOMMITTED]
2018-04-10 23:24:06,811 org.nutz.ioc.loader.combo.ComboIocLoader.createIocLoader(ComboIocLoader.java:110) DEBUG - found quartz -- org.nutz.integration.quartz.QuartzIocLoader
2018-04-10 23:24:06,815 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:57) DEBUG - Loaded 0 bean define from path=[] --> []
2018-04-10 23:24:06,820 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:98) INFO  - NutIoc init begin ...
2018-04-10 23:24:06,823 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:115) INFO  - ... NutIoc init complete
2018-04-10 23:24:06,828 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:159) INFO  - Build URL mapping by org.nutz.mvc.impl.UrlMappingImpl ...
2018-04-10 23:24:06,832 org.nutz.mvc.impl.NutLoading.createViewMakers(NutLoading.java:366) DEBUG - @Views(DefaultViewMaker.class)
2018-04-10 23:24:06,846 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( mvc/nutzbook-mvc-chain.js ) , regex( ^(.+[.])(js|json)$ )
2018-04-10 23:24:06,856 org.nutz.mvc.impl.chainconfig.JsonActionChainMakerConfiguretion.<init>(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.integration.shiro.NutShiroProcessor", "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"
   }
}
2018-04-10 23:24:06,857 org.nutz.mvc.impl.NutLoading.createChainMaker(NutLoading.java:263) DEBUG - @ChainBy(org.nutz.mvc.impl.NutActionChainMaker)
2018-04-10 23:24:06,863 org.nutz.mvc.impl.Loadings.scanModules(Loadings.java:154) DEBUG - module class location 'file:/E:/nutztomcat/tomcat/apache-tomcat-7.0.73/webapps/nutzbook/WEB-INF/classes/'
2018-04-10 23:24:06,863 org.nutz.mvc.impl.Loadings.scanModuleInPackage(Loadings.java:184) DEBUG -  > scan 'net.wendal.nutzbook'
2018-04-10 23:24:06,872 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 14 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-04-10 23:24:06,874 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:199) DEBUG -    >> add 'net.wendal.nutzbook.module.CaptchaModule'
2018-04-10 23:24:06,875 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:199) DEBUG -    >> add 'net.wendal.nutzbook.module.UserModule'
2018-04-10 23:24:06,875 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:199) DEBUG -    >> add 'net.wendal.nutzbook.module.UserProfileModule'
2018-04-10 23:24:07,074 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/add'                 >> (UserModule.java:69).add              : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,076 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/count'               >> (UserModule.java:38).count            : int        | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,085 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/delete'              >> (UserModule.java:97).delete           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,097 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/query'               >> (UserModule.java:107).query           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,103 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/index'               >> (UserModule.java:119).index           : void       | @Ok(jsp:jsp.user.list) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,106 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/update'              >> (UserModule.java:82).update           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,110 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/login'               >> (UserModule.java:48).login            : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,114 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/logout'              >> (UserModule.java:64).logout           : void       | @Ok(>>:/ ) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,117 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/get'         >> (UserProfileModule.java:52).get       : UserProfile | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,119 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/'            >> (UserProfileModule.java:142).index    : UserProfile | @Ok(jsp:jsp.user.profile) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,126 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/update'      >> (UserProfileModule.java:67).update    : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,142 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) INFO  - Init file-pool by: E:/nutztomcat/tomcat/apache-tomcat-7.0.73/webapps/nutzbook/WEB-INF/tmp/user_avatar [20000]
2018-04-10 23:24:07,145 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) DEBUG - file-pool.home: 'E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\tmp\user_avatar'
2018-04-10 23:24:07,146 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) INFO  - file-pool.cursor: 0
2018-04-10 23:24:07,152 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:100).uploadAvatar : void       | @Ok(>>:/user/profile) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,166 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:130).readAvatar : Object     | @Ok(raw:jpg) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,167 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/active/mail' >> (UserProfileModule.java:151).activeMail : Object     | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,168 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/active/mail' >> (UserProfileModule.java:178).activeMailCallback : String     | @Ok(raw  ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,170 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/captcha/next'             >> (CaptchaModule.java:23).next          : BufferedImage | @Ok(raw:png) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:24:07,170 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:217) INFO  - Found 16 module methods
2018-04-10 23:24:07,171 org.nutz.mvc.impl.NutLoading.evalLocalization(NutLoading.java:303) DEBUG - Localization: org.nutz.mvc.impl.NutMessageLoader('msg/')  dft<zh-CN>
2018-04-10 23:24:07,174 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( msg/ ) , regex( ^.+[.]properties$ )
2018-04-10 23:24:07,175 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:27) DEBUG - Load Messages in 1 resource : [[File[E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\classes\msg\zh-CN\user.properties]]]
2018-04-10 23:24:07,176 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:95) DEBUG - Message Loaded, size = 2
2018-04-10 23:24:07,177 org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:271) INFO  - Setup application...
2018-04-10 23:24:07,180 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dao'<interface org.nutz.dao.Dao>
2018-04-10 23:24:07,182 org.nutz.ioc.aop.SimpleAopMaker.<init>(SimpleAopMaker.java:79) DEBUG - Load AopConfigure for anno=org.nutz.ioc.aop.Aop by type=org.nutz.ioc.aop.config.impl.AnnotationAopConfigration
2018-04-10 23:24:07,183 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dao
2018-04-10 23:24:07,183 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dao
2018-04-10 23:24:07,188 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(dao) in JsonLoader(paths=[ioc/])
2018-04-10 23:24:07,188 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dao'<interface org.nutz.dao.Dao>
2018-04-10 23:24:07,202 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.dao.impl.NutDao without AOP
2018-04-10 23:24:07,203 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dao' to [app] 
2018-04-10 23:24:07,205 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2018-04-10 23:24:07,205 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dataSource
2018-04-10 23:24:07,205 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dataSource
2018-04-10 23:24:07,215 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(dataSource) in JsonLoader(paths=[ioc/])
2018-04-10 23:24:07,216 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dataSource'<>
2018-04-10 23:24:07,233 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class com.alibaba.druid.pool.DruidDataSource without AOP
2018-04-10 23:24:07,233 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dataSource' to [app] 
2018-04-10 23:24:07,235 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-04-10 23:24:07,235 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=conf
2018-04-10 23:24:07,236 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=conf
2018-04-10 23:24:07,246 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(conf) in JsonLoader(paths=[ioc/])
2018-04-10 23:24:07,246 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'conf'<>
2018-04-10 23:24:07,247 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.ioc.impl.PropertiesProxy without AOP
2018-04-10 23:24:07,247 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'conf' to [app] 
2018-04-10 23:24:07,286 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 3 resource by src( custom/ ) , regex( ^.+[.]properties$ )
2018-04-10 23:24:07,287 org.nutz.ioc.impl.PropertiesProxy.setPaths(PropertiesProxy.java:113) DEBUG - load properties from File[E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\classes\custom\cron.properties]
2018-04-10 23:24:07,287 org.nutz.ioc.impl.PropertiesProxy.setPaths(PropertiesProxy.java:113) DEBUG - load properties from File[E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\classes\custom\db.properties]
2018-04-10 23:24:07,288 org.nutz.ioc.impl.PropertiesProxy.setPaths(PropertiesProxy.java:113) DEBUG - load properties from File[E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\classes\custom\mail.properties]
2018-04-10 23:24:07,582 com.alibaba.druid.pool.DruidDataSource.init(DruidDataSource.java:715) INFO  - {dataSource-1} inited
2018-04-10 23:24:07,583 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2018-04-10 23:24:07,794 org.nutz.dao.jdbc.Jdbcs.<clinit>(Jdbcs.java:90) DEBUG - Jdbcs init complete
2018-04-10 23:24:07,794 org.nutz.dao.jdbc.Jdbcs.getExpert(Jdbcs.java:103) INFO  - Get Connection from DataSource for JdbcExpert, if you lock at here, check your database server and configure
2018-04-10 23:24:08,001 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:188) DEBUG - select expert : org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert
2018-04-10 23:24:08,010 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:199) DEBUG - JDBC Driver --> mysql-connector-java-5.1.37 ( Revision: 09940f05b4c98150f352e787a2549f11a2e9da93 )
2018-04-10 23:24:08,011 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:200) DEBUG - JDBC Name   --> MySQL Connector Java
2018-04-10 23:24:08,011 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:202) DEBUG - JDBC URL    --> jdbc:mysql://127.0.0.1:3306/nutzbook
2018-04-10 23:24:08,151 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_client=utf8
2018-04-10 23:24:08,151 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_connection=utf8
2018-04-10 23:24:08,151 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_database=utf8
2018-04-10 23:24:08,152 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_filesystem=binary
2018-04-10 23:24:08,152 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_results=
2018-04-10 23:24:08,153 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_server=utf8
2018-04-10 23:24:08,153 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_system=utf8
2018-04-10 23:24:08,153 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_sets_dir=D:\mysql\share\charsets\
2018-04-10 23:24:08,155 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:213) DEBUG - Mysql : binlog_format=STATEMENT
2018-04-10 23:24:08,155 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:220) DEBUG - Mysql : database=nutzbook
2018-04-10 23:24:08,156 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:226) DEBUG - Mysql : user=root@localhost
2018-04-10 23:24:08,203 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:221) DEBUG - Database info --> MYSQL:[MySQL - 5.5.56]
2018-04-10 23:24:08,217 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 14 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-04-10 23:24:08,317 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT COUNT(*) FROM t_user 
2018-04-10 23:24:08,322 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'nutQuartzCronJobFactory'<class org.nutz.integration.quartz.NutQuartzCronJobFactory>
2018-04-10 23:24:08,322 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=nutQuartzCronJobFactory
2018-04-10 23:24:08,324 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:125) ERROR - Error happend during start serivce!
org.nutz.ioc.IocException: IocBean[nutQuartzCronJobFactory] For object [nutQuartzCronJobFactory] - type:[class org.nutz.integration.quartz.NutQuartzCronJobFactory]
	at org.nutz.ioc.impl.NutIoc.get(NutIoc.java:218)
	at org.nutz.ioc.impl.NutIoc.get(NutIoc.java:239)
	at org.nutz.ioc.impl.NutIoc.get(NutIoc.java:146)
	at net.wendal.nutzbook.MainSetup.init(MainSetup.java:33)
	at org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:274)
	at org.nutz.mvc.impl.NutLoading.load(NutLoading.java:121)
	at org.nutz.mvc.ActionHandler.<init>(ActionHandler.java:19)
	at org.nutz.mvc.NutFilter._init(NutFilter.java:91)
	at org.nutz.mvc.NutFilter.init(NutFilter.java:69)
	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.<init>(ApplicationFilterConfig.java:105)
	at org.apache.catalina.core.StandardContext.filterStart(StandardContext.java:4958)
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5652)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:145)
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:899)
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:875)
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652)
	at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1260)
	at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:2002)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
	at java.lang.Thread.run(Thread.java:619)
Caused by: org.nutz.ioc.ObjectLoadException: Object 'nutQuartzCronJobFactory' without define!
	at org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:166)
	at org.nutz.ioc.impl.NutIoc.get(NutIoc.java:180)
	... 25 more
2018-04-10 23:24:08,327 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:127) ERROR - try to depose ioc
2018-04-10 23:24:08,330 org.nutz.ioc.impl.NutIoc.depose(NutIoc.java:255) INFO  - org.nutz.ioc.impl.NutIoc@1358474873 is closing. startup date [18-04-10 23:24:06.820]
2018-04-10 23:24:08,331 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'conf' ...
2018-04-10 23:24:08,331 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'dao' ...
2018-04-10 23:24:08,331 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:113) DEBUG - Depose object 'dataSource' ...
2018-04-10 23:24:08,336 com.alibaba.druid.pool.DruidDataSource.close(DruidDataSource.java:1444) INFO  - {dataSource-1} closed
2018-04-10 23:24:08,337 org.nutz.ioc.impl.NutIoc.depose(NutIoc.java:262) INFO  - org.nutz.ioc.impl.NutIoc@1358474873 is deposed. startup date [18-04-10 23:24:06.820]
2018-4-10 23:24:08 org.apache.catalina.core.StandardContext filterStart
严重: Exception starting filter nutz
org.nutz.mvc.LoadingException: org.nutz.ioc.IocException: IocBean[nutQuartzCronJobFactory] For object [nutQuartzCronJobFactory] - type:[class org.nutz.integration.quartz.NutQuartzCronJobFactory]
	at org.nutz.mvc.LoadingException$FC$9a86caf9ff5d3b587784fcdf555a4a7d.invoke(LoadingException.java)
	at org.nutz.lang.born.AbstractConstructorBorning.call(AbstractConstructorBorning.java:25)
	at org.nutz.lang.born.ConstructorBorning.born(ConstructorBorning.java:19)
	at org.nutz.lang.born.BornContext.doBorn(BornContext.java:60)
	at org.nutz.lang.Mirror.born(Mirror.java:990)
	at org.nutz.lang.Lang.wrapThrow(Lang.java:200)
	at org.nutz.mvc.impl.NutLoading.load(NutLoading.java:135)
	at org.nutz.mvc.ActionHandler.<init>(ActionHandler.java:19)
	at org.nutz.mvc.NutFilter._init(NutFilter.java:91)
	at org.nutz.mvc.NutFilter.init(NutFilter.java:69)
	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.<init>(ApplicationFilterConfig.java:105)
	at org.apache.catalina.core.StandardContext.filterStart(StandardContext.java:4958)
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5652)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:145)
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:899)
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:875)
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652)
	at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1260)
	at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:2002)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
	at java.lang.Thread.run(Thread.java:619)
Caused by: org.nutz.ioc.IocException: IocBean[nutQuartzCronJobFactory] For object [nutQuartzCronJobFactory] - type:[class org.nutz.integration.quartz.NutQuartzCronJobFactory]
	at org.nutz.ioc.impl.NutIoc.get(NutIoc.java:218)
	at org.nutz.ioc.impl.NutIoc.get(NutIoc.java:239)
	at org.nutz.ioc.impl.NutIoc.get(NutIoc.java:146)
	at net.wendal.nutzbook.MainSetup.init(MainSetup.java:33)
	at org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:274)
	at org.nutz.mvc.impl.NutLoading.load(NutLoading.java:121)
	... 20 more
Caused by: org.nutz.ioc.ObjectLoadException: Object 'nutQuartzCronJobFactory' without define!
	at org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:166)
	at org.nutz.ioc.impl.NutIoc.get(NutIoc.java:180)
	... 25 more
2018-4-10 23:24:08 org.apache.catalina.core.StandardContext startInternal
严重: One or more Filters failed to start. Full details will be found in the appropriate container log file
2018-4-10 23:24:08 org.apache.catalina.core.StandardContext startInternal
严重: Context [/nutzbook] startup failed due to previous errors
2018-4-10 23:24:08 org.apache.catalina.core.ApplicationContext log
信息: Cleaning up Shiro Environment
2018-4-10 23:24:08 org.apache.catalina.loader.WebappClassLoaderBase clearReferencesJdbc
严重: The web application [/nutzbook] registered the JDBC driver [com.alibaba.druid.proxy.DruidDriver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
2018-4-10 23:24:08 org.apache.catalina.loader.WebappClassLoaderBase clearReferencesJdbc
严重: The web application [/nutzbook] registered the JDBC driver [com.mysql.jdbc.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
2018-4-10 23:24:08 org.apache.catalina.loader.WebappClassLoaderBase clearReferencesThreads
严重: The web application [/nutzbook] appears to have started a thread named [Abandoned connection cleanup thread] but has failed to stop it. This is very likely to create a memory leak.
2018-4-10 23:24:08 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook has finished in 3,823 ms
2018-4-10 23:24:08 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\ROOT
2018-4-10 23:24:08 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\ROOT has finished in 61 ms
2018-4-10 23:24:08 org.apache.coyote.AbstractProtocol start
信息: Starting ProtocolHandler ["http-apr-8080"]
2018-4-10 23:24:08 org.apache.coyote.AbstractProtocol start
信息: Starting ProtocolHandler ["ajp-apr-8009"]
2018-4-10 23:24:08 org.apache.catalina.startup.Catalina start
信息: Server startup in 4825 ms

wendal

9楼•2448天前

loading [quartz.js]

你没删呢

来自炫酷的 NutzCN

已经过去ue(qq_2b4e731b) 10楼•2448天前

好的，已经删除了

2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: Server version:        Apache Tomcat/7.0.73
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: Server built:          Nov 7 2016 21:27:23 UTC
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: Server number:         7.0.73.0
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: OS Name:               Windows Vista
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: OS Version:            6.1
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: Architecture:          amd64
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: Java Home:             D:\myEclipse\Common\binary\com.sun.java.jdk.win32.x86_64_1.6.0.013\jre
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: JVM Version:           1.6.0_13-b03
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: JVM Vendor:            Sun Microsystems Inc.
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: CATALINA_BASE:         E:/nutztomcat/tomcat/apache-tomcat-7.0.73
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: CATALINA_HOME:         E:/nutztomcat/tomcat/apache-tomcat-7.0.73
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dcatalina.home=E:/nutztomcat/tomcat/apache-tomcat-7.0.73
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dcatalina.base=E:/nutztomcat/tomcat/apache-tomcat-7.0.73
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Djava.endorsed.dirs=E:/nutztomcat/tomcat/apache-tomcat-7.0.73/common/endorsed
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Djava.io.tmpdir=E:/nutztomcat/tomcat/apache-tomcat-7.0.73/temp
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Djava.library.path=D:/myEclipse/Common/binary/com.sun.java.jdk.win32.x86_64_1.6.0.013/bin;E:/nutztomcat/tomcat/apache-tomcat-7.0.73/bin
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dsun.io.useCanonCaches=false
2018-4-10 23:27:15 org.apache.catalina.startup.VersionLoggerListener log
信息: Command line argument: -Dfile.encoding=UTF-8
2018-4-10 23:27:15 org.apache.catalina.core.AprLifecycleListener lifecycleEvent
信息: Loaded APR based Apache Tomcat Native library 1.2.10 using APR version 1.5.2.
2018-4-10 23:27:15 org.apache.catalina.core.AprLifecycleListener lifecycleEvent
信息: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
2018-4-10 23:27:15 org.apache.catalina.core.AprLifecycleListener initializeSSL
信息: OpenSSL successfully initialized (OpenSSL 1.0.2j  26 Sep 2016)
2018-4-10 23:27:15 org.apache.coyote.AbstractProtocol init
信息: Initializing ProtocolHandler ["http-apr-8080"]
2018-4-10 23:27:15 org.apache.coyote.AbstractProtocol init
信息: Initializing ProtocolHandler ["ajp-apr-8009"]
2018-4-10 23:27:15 org.apache.catalina.startup.Catalina load
信息: Initialization processed in 958 ms
2018-4-10 23:27:15 org.apache.catalina.core.StandardService startInternal
信息: Starting service Catalina
2018-4-10 23:27:15 org.apache.catalina.core.StandardEngine startInternal
信息: Starting Servlet Engine: Apache Tomcat/7.0.73
2018-4-10 23:27:15 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\docs
2018-4-10 23:27:16 org.apache.tomcat.websocket.server.WsSci onStartup
信息: JSR 356 WebSocket (Java WebSocket 1.1) support is not available when running on Java 6. To suppress this message, run Tomcat on Java 7, remove the WebSocket JARs from $CATALINA_HOME/lib or add the WebSocket JARs to the tomcat.util.scan.DefaultJarScanner.jarsToSkip property in $CATALINA_BASE/conf/catalina.properties. Note that the deprecated Tomcat 7 WebSocket API will be available. 
2018-4-10 23:27:16 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\docs has finished in 390 ms
2018-4-10 23:27:16 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\examples
2018-4-10 23:27:16 org.apache.catalina.core.ApplicationContext log
信息: ContextListener: contextInitialized()
2018-4-10 23:27:16 org.apache.catalina.core.ApplicationContext log
信息: SessionListener: contextInitialized()
2018-4-10 23:27:16 org.apache.catalina.core.ApplicationContext log
信息: ContextListener: attributeAdded('org.apache.jasper.compiler.TldLocationsCache', 'org.apache.jasper.compiler.TldLocationsCache@e11e831')
2018-4-10 23:27:16 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\examples has finished in 395 ms
2018-4-10 23:27:16 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\host-manager
2018-4-10 23:27:16 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\host-manager has finished in 59 ms
2018-4-10 23:27:16 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\manager
2018-4-10 23:27:16 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\manager has finished in 59 ms
2018-4-10 23:27:16 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook
2018-4-10 23:27:17 org.apache.catalina.startup.TaglibUriRule body
信息: TLD skipped. URI: http://java.sun.com/jstl/core is already defined
2018-4-10 23:27:17 org.apache.catalina.startup.TaglibUriRule body
信息: TLD skipped. URI: http://java.sun.com/jstl/fmt is already defined
2018-4-10 23:27:17 org.apache.catalina.startup.TaglibUriRule body
信息: TLD skipped. URI: http://java.sun.com/jstl/sql is already defined
2018-4-10 23:27:17 org.apache.catalina.startup.TaglibUriRule body
信息: TLD skipped. URI: http://java.sun.com/jstl/xml is already defined
2018-4-10 23:27:17 org.apache.catalina.startup.TldConfig execute
信息: 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.
2018-4-10 23:27:17 org.apache.catalina.core.ApplicationContext log
信息: Initializing Shiro environment
2018-04-10 23:27:17,963 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:128) INFO  - Starting Shiro environment initialization.
2018-04-10 23:27:17,986 org.apache.shiro.web.env.IniWebEnvironment.init(IniWebEnvironment.java:76) DEBUG - Checking any specified config locations.
2018-04-10 23:27:17,986 org.apache.shiro.web.env.IniWebEnvironment.init(IniWebEnvironment.java:81) DEBUG - No INI instance or config locations specified.  Trying default config locations.
2018-04-10 23:27:17,991 org.apache.shiro.io.ResourceUtils.loadFromClassPath(ResourceUtils.java:159) DEBUG - Opening resource from class path [shiro.ini]
2018-04-10 23:27:17,996 org.apache.shiro.config.Ini.load(Ini.java:351) DEBUG - Parsing [main]
2018-04-10 23:27:17,996 org.apache.shiro.config.Ini.load(Ini.java:351) DEBUG - Parsing [urls]
2018-04-10 23:27:17,998 org.apache.shiro.web.env.IniWebEnvironment.getDefaultIni(IniWebEnvironment.java:136) DEBUG - Discovered non-empty INI configuration at location 'classpath:shiro.ini'.  Using for configuration.
2018-04-10 23:27:18,002 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:122) DEBUG - Creating instance from Ini [sections=urls]
2018-04-10 23:27:18,259 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:122) DEBUG - Creating instance from Ini [sections=urls]
2018-04-10 23:27:18,264 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/*] from String definition [anon]
2018-04-10 23:27:18,264 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/*] to filter [anon] with config [null]
2018-04-10 23:27:18,266 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]
2018-04-10 23:27:18,266 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:141) INFO  - Shiro environment initialized in 299 ms.
2018-04-10 23:27:18,293 org.nutz.mvc.NutFilter._init(NutFilter.java:85) INFO  - NutFilter[nutz] starting ...
2018-04-10 23:27:18,324 org.nutz.resource.Scans.printLocations(Scans.java:521) DEBUG - Locations count=5 time use 19ms
2018-04-10 23:27:18,443 org.nutz.resource.Scans.printLocations(Scans.java:521) DEBUG - Locations count=28 time use 119ms
2018-04-10 23:27:18,528 org.nutz.castor.Castors.reload(Castors.java:115) DEBUG - Using 95 castor for Castors
2018-04-10 23:27:18,559 org.nutz.mvc.config.AbstractNutConfig.createLoading(AbstractNutConfig.java:59) DEBUG - Loading by class org.nutz.mvc.impl.NutLoading
2018-04-10 23:27:18,562 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:55) INFO  - Nutz Version : 1.r.62 
2018-04-10 23:27:18,562 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:56) INFO  - Nutz.Mvc[nutz] is initializing ...
2018-04-10 23:27:18,562 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:60) DEBUG - Web Container Information:
2018-04-10 23:27:18,563 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:61) DEBUG -  - Default Charset : UTF-8
2018-04-10 23:27:18,564 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:62) DEBUG -  - Current . path  : E:\nutztomcat\tomcat\apache-tomcat-7.0.73\bin\.
2018-04-10 23:27:18,564 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:63) DEBUG -  - Java Version    : 1.6.0_13
2018-04-10 23:27:18,564 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:64) DEBUG -  - File separator  : \
2018-04-10 23:27:18,564 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:65) DEBUG -  - Timezone        : Asia/Shanghai
2018-04-10 23:27:18,564 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:66) DEBUG -  - OS              : Windows Vista amd64
2018-04-10 23:27:18,564 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:67) DEBUG -  - ServerInfo      : Apache Tomcat/7.0.73
2018-04-10 23:27:18,564 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:68) DEBUG -  - Servlet API     : 3.0
2018-04-10 23:27:18,565 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:73) DEBUG -  - ContextPath     : /nutzbook
2018-04-10 23:27:18,565 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:74) DEBUG -  - context.tempdir : E:\nutztomcat\tomcat\apache-tomcat-7.0.73\work\Catalina\localhost\nutzbook
2018-04-10 23:27:18,565 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:75) DEBUG -  - MainModule      : net.wendal.nutzbook.MainModule
2018-04-10 23:27:18,565 org.nutz.mvc.impl.NutLoading.createContext(NutLoading.java:235) DEBUG - >> app.root = E:/nutztomcat/tomcat/apache-tomcat-7.0.73/webapps/nutzbook
2018-04-10 23:27:18,569 org.nutz.mvc.impl.NutLoading.createIoc(NutLoading.java:376) DEBUG - @IocBy(type=org.nutz.mvc.ioc.provider.ComboIocProvider, args=["*js", "ioc/", "*anno", "net.wendal.nutzbook", "*tx", "*quartz"],init=[])
2018-04-10 23:27:18,651 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 2 resource by src( ioc/ ) , regex( ^(.+[.])(js|json)$ )
2018-04-10 23:27:18,652 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:49) DEBUG - loading [dao.js]
2018-04-10 23:27:18,661 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:49) DEBUG - loading [mail.js]
2018-04-10 23:27:18,662 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:57) DEBUG - Loaded 5 bean define from path=[ioc/] --> [htmlEmail, dataSource, dao, conf, emailAuthenticator]
2018-04-10 23:27:18,681 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 14 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-04-10 23:27:18,745 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.CaptchaModule
2018-04-10 23:27:18,760 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserModule
2018-04-10 23:27:18,775 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserProfileModule
2018-04-10 23:27:18,791 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.quartz.job.CleanNonActiveUserJob
2018-04-10 23:27:18,794 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:76) DEBUG - Found @IocBean : class net.wendal.nutzbook.service.EmailServiceImpl
2018-04-10 23:27:18,795 org.nutz.ioc.loader.annotation.AnnotationIocLoader.<init>(AnnotationIocLoader.java:52) INFO  - Found 5 classes in 1 base-packages!
beans = ["captchaModule", "cleanNonActiveUserJob", "userProfileModule", "emailService", "userModule"]
2018-04-10 23:27:18,805 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:40) DEBUG - Loaded 5 bean define from reader --
[txREPEATABLE_READ, txREAD_COMMITTED, txNONE, txSERIALIZABLE, txREAD_UNCOMMITTED]
2018-04-10 23:27:18,809 org.nutz.ioc.loader.combo.ComboIocLoader.createIocLoader(ComboIocLoader.java:110) DEBUG - found quartz -- org.nutz.integration.quartz.QuartzIocLoader
2018-04-10 23:27:18,814 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:57) DEBUG - Loaded 0 bean define from path=[] --> []
2018-04-10 23:27:18,820 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:98) INFO  - NutIoc init begin ...
2018-04-10 23:27:18,825 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:115) INFO  - ... NutIoc init complete
2018-04-10 23:27:18,832 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:159) INFO  - Build URL mapping by org.nutz.mvc.impl.UrlMappingImpl ...
2018-04-10 23:27:18,836 org.nutz.mvc.impl.NutLoading.createViewMakers(NutLoading.java:366) DEBUG - @Views(DefaultViewMaker.class)
2018-04-10 23:27:18,854 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( mvc/nutzbook-mvc-chain.js ) , regex( ^(.+[.])(js|json)$ )
2018-04-10 23:27:18,864 org.nutz.mvc.impl.chainconfig.JsonActionChainMakerConfiguretion.<init>(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.integration.shiro.NutShiroProcessor", "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"
   }
}
2018-04-10 23:27:18,865 org.nutz.mvc.impl.NutLoading.createChainMaker(NutLoading.java:263) DEBUG - @ChainBy(org.nutz.mvc.impl.NutActionChainMaker)
2018-04-10 23:27:18,871 org.nutz.mvc.impl.Loadings.scanModules(Loadings.java:154) DEBUG - module class location 'file:/E:/nutztomcat/tomcat/apache-tomcat-7.0.73/webapps/nutzbook/WEB-INF/classes/'
2018-04-10 23:27:18,871 org.nutz.mvc.impl.Loadings.scanModuleInPackage(Loadings.java:184) DEBUG -  > scan 'net.wendal.nutzbook'
2018-04-10 23:27:18,877 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 14 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-04-10 23:27:18,879 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:199) DEBUG -    >> add 'net.wendal.nutzbook.module.CaptchaModule'
2018-04-10 23:27:18,879 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:199) DEBUG -    >> add 'net.wendal.nutzbook.module.UserModule'
2018-04-10 23:27:18,879 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:199) DEBUG -    >> add 'net.wendal.nutzbook.module.UserProfileModule'
2018-04-10 23:27:19,062 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/add'                 >> (UserModule.java:69).add              : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,063 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/count'               >> (UserModule.java:38).count            : int        | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,068 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/delete'              >> (UserModule.java:97).delete           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,076 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/query'               >> (UserModule.java:107).query           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,079 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/index'               >> (UserModule.java:119).index           : void       | @Ok(jsp:jsp.user.list) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,081 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/update'              >> (UserModule.java:82).update           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,084 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/login'               >> (UserModule.java:48).login            : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,085 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/logout'              >> (UserModule.java:64).logout           : void       | @Ok(>>:/ ) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,089 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/captcha/next'             >> (CaptchaModule.java:23).next          : BufferedImage | @Ok(raw:png) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,092 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/get'         >> (UserProfileModule.java:52).get       : UserProfile | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,093 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/'            >> (UserProfileModule.java:142).index    : UserProfile | @Ok(jsp:jsp.user.profile) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,099 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/update'      >> (UserProfileModule.java:67).update    : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,112 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) INFO  - Init file-pool by: E:/nutztomcat/tomcat/apache-tomcat-7.0.73/webapps/nutzbook/WEB-INF/tmp/user_avatar [20000]
2018-04-10 23:27:19,115 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) DEBUG - file-pool.home: 'E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\tmp\user_avatar'
2018-04-10 23:27:19,116 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) INFO  - file-pool.cursor: 0
2018-04-10 23:27:19,122 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:100).uploadAvatar : void       | @Ok(>>:/user/profile) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,124 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:130).readAvatar : Object     | @Ok(raw:jpg) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,137 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/active/mail' >> (UserProfileModule.java:151).activeMail : Object     | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,140 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/active/mail' >> (UserProfileModule.java:178).activeMailCallback : String     | @Ok(raw  ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-04-10 23:27:19,140 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:217) INFO  - Found 16 module methods
2018-04-10 23:27:19,140 org.nutz.mvc.impl.NutLoading.evalLocalization(NutLoading.java:303) DEBUG - Localization: org.nutz.mvc.impl.NutMessageLoader('msg/')  dft<zh-CN>
2018-04-10 23:27:19,144 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( msg/ ) , regex( ^.+[.]properties$ )
2018-04-10 23:27:19,144 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:27) DEBUG - Load Messages in 1 resource : [[File[E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\classes\msg\zh-CN\user.properties]]]
2018-04-10 23:27:19,145 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:95) DEBUG - Message Loaded, size = 2
2018-04-10 23:27:19,146 org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:271) INFO  - Setup application...
2018-04-10 23:27:19,149 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dao'<interface org.nutz.dao.Dao>
2018-04-10 23:27:19,150 org.nutz.ioc.aop.SimpleAopMaker.<init>(SimpleAopMaker.java:79) DEBUG - Load AopConfigure for anno=org.nutz.ioc.aop.Aop by type=org.nutz.ioc.aop.config.impl.AnnotationAopConfigration
2018-04-10 23:27:19,151 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dao
2018-04-10 23:27:19,151 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dao
2018-04-10 23:27:19,155 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(dao) in JsonLoader(paths=[ioc/])
2018-04-10 23:27:19,156 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dao'<interface org.nutz.dao.Dao>
2018-04-10 23:27:19,170 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.dao.impl.NutDao without AOP
2018-04-10 23:27:19,171 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dao' to [app] 
2018-04-10 23:27:19,173 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2018-04-10 23:27:19,173 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dataSource
2018-04-10 23:27:19,174 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dataSource
2018-04-10 23:27:19,184 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(dataSource) in JsonLoader(paths=[ioc/])
2018-04-10 23:27:19,184 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dataSource'<>
2018-04-10 23:27:19,202 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class com.alibaba.druid.pool.DruidDataSource without AOP
2018-04-10 23:27:19,203 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dataSource' to [app] 
2018-04-10 23:27:19,205 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-04-10 23:27:19,205 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=conf
2018-04-10 23:27:19,205 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=conf
2018-04-10 23:27:19,216 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(conf) in JsonLoader(paths=[ioc/])
2018-04-10 23:27:19,216 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'conf'<>
2018-04-10 23:27:19,217 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.ioc.impl.PropertiesProxy without AOP
2018-04-10 23:27:19,217 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'conf' to [app] 
2018-04-10 23:27:19,253 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 3 resource by src( custom/ ) , regex( ^.+[.]properties$ )
2018-04-10 23:27:19,254 org.nutz.ioc.impl.PropertiesProxy.setPaths(PropertiesProxy.java:113) DEBUG - load properties from File[E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\classes\custom\cron.properties]
2018-04-10 23:27:19,254 org.nutz.ioc.impl.PropertiesProxy.setPaths(PropertiesProxy.java:113) DEBUG - load properties from File[E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\classes\custom\db.properties]
2018-04-10 23:27:19,255 org.nutz.ioc.impl.PropertiesProxy.setPaths(PropertiesProxy.java:113) DEBUG - load properties from File[E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook\WEB-INF\classes\custom\mail.properties]
2018-04-10 23:27:19,526 com.alibaba.druid.pool.DruidDataSource.init(DruidDataSource.java:715) INFO  - {dataSource-1} inited
2018-04-10 23:27:19,527 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2018-04-10 23:27:19,714 org.nutz.dao.jdbc.Jdbcs.<clinit>(Jdbcs.java:90) DEBUG - Jdbcs init complete
2018-04-10 23:27:19,714 org.nutz.dao.jdbc.Jdbcs.getExpert(Jdbcs.java:103) INFO  - Get Connection from DataSource for JdbcExpert, if you lock at here, check your database server and configure
2018-04-10 23:27:19,931 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:188) DEBUG - select expert : org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert
2018-04-10 23:27:19,939 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:199) DEBUG - JDBC Driver --> mysql-connector-java-5.1.37 ( Revision: 09940f05b4c98150f352e787a2549f11a2e9da93 )
2018-04-10 23:27:19,940 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:200) DEBUG - JDBC Name   --> MySQL Connector Java
2018-04-10 23:27:19,940 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:202) DEBUG - JDBC URL    --> jdbc:mysql://127.0.0.1:3306/nutzbook
2018-04-10 23:27:20,074 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_client=utf8
2018-04-10 23:27:20,074 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_connection=utf8
2018-04-10 23:27:20,075 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_database=utf8
2018-04-10 23:27:20,075 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_filesystem=binary
2018-04-10 23:27:20,075 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_results=
2018-04-10 23:27:20,076 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_server=utf8
2018-04-10 23:27:20,076 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_set_system=utf8
2018-04-10 23:27:20,076 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:206) DEBUG - Mysql : character_sets_dir=D:\mysql\share\charsets\
2018-04-10 23:27:20,078 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:213) DEBUG - Mysql : binlog_format=STATEMENT
2018-04-10 23:27:20,079 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:220) DEBUG - Mysql : database=nutzbook
2018-04-10 23:27:20,079 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:226) DEBUG - Mysql : user=root@localhost
2018-04-10 23:27:20,132 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:221) DEBUG - Database info --> MYSQL:[MySQL - 5.5.56]
2018-04-10 23:27:20,147 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 14 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-04-10 23:27:20,239 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT COUNT(*) FROM t_user 
2018-04-10 23:27:20,244 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'nutQuartzCronJobFactory'<class org.nutz.integration.quartz.NutQuartzCronJobFactory>
2018-04-10 23:27:20,244 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=nutQuartzCronJobFactory
2018-04-10 23:27:20,244 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=nutQuartzCronJobFactory
2018-04-10 23:27:20,245 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(nutQuartzCronJobFactory) in QuartzIocLoader@80117849
2018-04-10 23:27:20,245 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'nutQuartzCronJobFactory'<class org.nutz.integration.quartz.NutQuartzCronJobFactory>
2018-04-10 23:27:20,247 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.integration.quartz.NutQuartzCronJobFactory without AOP
2018-04-10 23:27:20,247 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'nutQuartzCronJobFactory' to [app] 
2018-04-10 23:27:20,254 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'scheduler'<>
2018-04-10 23:27:20,254 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=scheduler
2018-04-10 23:27:20,254 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=scheduler
2018-04-10 23:27:20,255 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(scheduler) in QuartzIocLoader@80117849
2018-04-10 23:27:20,255 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'scheduler'<>
2018-04-10 23:27:20,255 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'scheduler' to [app] 
2018-04-10 23:27:20,303 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1172) INFO  - Using default implementation for ThreadExecutor
2018-04-10 23:27:20,324 org.quartz.core.SchedulerSignalerImpl.<init>(SchedulerSignalerImpl.java:61) INFO  - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2018-04-10 23:27:20,324 org.quartz.core.QuartzScheduler.<init>(QuartzScheduler.java:240) INFO  - Quartz Scheduler v.2.2.3 created.
2018-04-10 23:27:20,325 org.quartz.simpl.RAMJobStore.initialize(RAMJobStore.java:155) INFO  - RAMJobStore initialized.
2018-04-10 23:27:20,325 org.quartz.core.QuartzScheduler.initialize(QuartzScheduler.java:305) INFO  - Scheduler meta-data: Quartz Scheduler (v2.2.3) '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.

2018-04-10 23:27:20,326 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1327) INFO  - Quartz scheduler 'NutzbookScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
2018-04-10 23:27:20,326 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1331) INFO  - Quartz scheduler version: 2.2.3
2018-04-10 23:27:20,326 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'jobFactory'<>
2018-04-10 23:27:20,326 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=jobFactory
2018-04-10 23:27:20,326 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=jobFactory
2018-04-10 23:27:20,327 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:161) DEBUG - Found IocObject(jobFactory) in QuartzIocLoader@80117849
2018-04-10 23:27:20,327 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'jobFactory'<>
2018-04-10 23:27:20,328 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.integration.quartz.NutQuartzJobFactory without AOP
2018-04-10 23:27:20,328 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'jobFactory' to [app] 
2018-04-10 23:27:20,361 org.quartz.core.QuartzScheduler.setJobFactory(QuartzScheduler.java:2311) INFO  - JobFactory set to: org.nutz.integration.quartz.NutQuartzJobFactory@b45c414
2018-04-10 23:27:20,361 org.quartz.core.QuartzScheduler.start(QuartzScheduler.java:575) INFO  - Scheduler NutzbookScheduler_$_NON_CLUSTERED started.
2018-04-10 23:27:20,361 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:276) DEBUG - batch acquisition of 0 triggers
2018-04-10 23:27:20,361 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-04-10 23:27:20,362 org.nutz.integration.quartz.NutQuartzCronJobFactory.init(NutQuartzCronJobFactory.java:35) DEBUG - job define name=net.wendal.nutzbook.quartz.job.CleanNonActiveUserJob cron=0 0/2 * * * ?
2018-04-10 23:27:20,379 org.nutz.integration.quartz.NutQuartzCronJobFactory.init(NutQuartzCronJobFactory.java:28) DEBUG - found cron job packages = net.wendal.nutzbook.quartz.job
2018-04-10 23:27:20,380 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( net/wendal/nutzbook/quartz/job/ ) , regex( ^.+[.]class$ )
2018-04-10 23:27:20,383 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:141) INFO  - Nutz.Mvc[nutz] is up in 1818ms
2018-04-10 23:27:20,384 org.nutz.mvc.NutFilter._init(NutFilter.java:117) INFO  - exclusionsPrefix  = ^(/rs/|/druid/)
2018-4-10 23:27:20 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\nutzbook has finished in 3,746 ms
2018-4-10 23:27:20 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\ROOT
2018-04-10 23:27:20,403 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:276) DEBUG - batch acquisition of 0 triggers
2018-4-10 23:27:20 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\nutztomcat\tomcat\apache-tomcat-7.0.73\webapps\ROOT has finished in 68 ms
2018-4-10 23:27:20 org.apache.coyote.AbstractProtocol start
信息: Starting ProtocolHandler ["http-apr-8080"]
2018-4-10 23:27:20 org.apache.coyote.AbstractProtocol start
信息: Starting ProtocolHandler ["ajp-apr-8009"]
2018-4-10 23:27:20 org.apache.catalina.startup.Catalina start
信息: Server startup in 4769 ms

已经过去ue(qq_2b4e731b) 11楼•2448天前

谢谢大佬了，睡咯睡咯。安
@wendal

请先登陆