2020-01-07 09:11:49,459 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:98) INFO  - NutIoc init begin ...
2020-01-07 09:11:49,460 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:115) INFO  - ... NutIoc init complete
2020-01-07 09:11:49,462 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:159) INFO  - Build URL mapping by org.nutz.mvc.impl.UrlMappingImpl ...
2020-01-07 09:11:49,463 org.nutz.mvc.impl.NutLoading.createViewMakers(NutLoading.java:369) DEBUG - @Views(DefaultViewMaker.class)
2020-01-07 09:11:49,469 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( mvc/nutzbook-mvc-chain.js ) , regex( ^(.+[.])(js|json)$ )
2020-01-07 09:11:49,475 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.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"
   }
}
2020-01-07 09:11:49,475 org.nutz.mvc.impl.NutLoading.createChainMaker(NutLoading.java:266) DEBUG - @ChainBy(org.nutz.mvc.impl.NutActionChainMaker)
2020-01-07 09:11:49,478 org.nutz.mvc.impl.Loadings.scanModules(Loadings.java:154) DEBUG - module class location 'file:/E:/eclipse/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/webapps/nutzbook_dev_prepare/WEB-INF/classes/'
2020-01-07 09:11:49,478 org.nutz.mvc.impl.Loadings.scanModuleInPackage(Loadings.java:184) DEBUG -  > scan 'net.wendal.nutzbook'
2020-01-07 09:11:49,483 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 11 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2020-01-07 09:11:49,484 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG -    >> add 'net.wendal.nutzbook.module.UserModule'
2020-01-07 09:11:49,484 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG -    >> add 'net.wendal.nutzbook.module.UserProfileModule'
2020-01-07 09:11:49,496 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/'                    >> (UserModule.java:100).index           : void       | @Ok(jsp:jsp.user.list) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,505 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/add'                 >> (UserModule.java:55).add              : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,506 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/count'               >> (UserModule.java:32).count            : int        | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,507 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/update'              >> (UserModule.java:66).update           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,510 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/delete'              >> (UserModule.java:80).delete           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,512 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/query'               >> (UserModule.java:89).query            : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,512 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/error'               >> (UserModule.java:137).error           : void       | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,514 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/login'               >> (UserModule.java:38).login            : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,515 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/logout'              >> (UserModule.java:50).logout           : void       | @Ok(>>:/ ) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,516 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/'            >> (UserProfileModule.java:148).index    : UserProfile | @Ok(jsp:jsp.user.profile) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,516 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/get'         >> (UserProfileModule.java:50).get       : UserProfile | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,518 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/update'      >> (UserProfileModule.java:65).update    : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,519 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/delete'      >> (UserProfileModule.java:92).delete    : Object     | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,521 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:138).readAvatar : Object     | @Ok(raw:jpg) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,525 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) INFO  - Init file-pool by: E:/eclipse/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/webapps/nutzbook_dev_prepare/WEB-INF/tmp/user_avatar [20000]
2020-01-07 09:11:49,526 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) DEBUG - file-pool.home: 'E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\tmp\user_avatar'
2020-01-07 09:11:49,527 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) INFO  - file-pool.cursor: 0
2020-01-07 09:11:49,529 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:108).uploadAvatar : void       | @Ok(>>:/user/profile) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:49,530 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:220) INFO  - Found 15 module methods
2020-01-07 09:11:49,530 org.nutz.mvc.impl.NutLoading.evalLocalization(NutLoading.java:306) DEBUG - Localization: org.nutz.mvc.impl.NutMessageLoader('msg/')  dft<zh-CN>
2020-01-07 09:11:49,532 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( msg/ ) , regex( ^.+[.]properties$ )
2020-01-07 09:11:49,532 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:27) DEBUG - Load Messages in 1 resource : [[NutResource[zh-CN/user.properties]]]
2020-01-07 09:11:49,533 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:95) DEBUG - Message Loaded, size = 2
2020-01-07 09:11:49,534 org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:274) INFO  - Setup application...
2020-01-07 09:11:49,534 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dao'<interface org.nutz.dao.Dao>
2020-01-07 09:11:49,535 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get '$aop_async'<interface org.nutz.ioc.aop.config.AopConfigration>
2020-01-07 09:11:49,536 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=$aop_async
2020-01-07 09:11:49,537 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject($aop_async) in AsyncAopIocLoader@798913859
2020-01-07 09:11:49,537 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'$aop_async'<interface org.nutz.ioc.aop.config.AopConfigration>
2020-01-07 09:11:49,538 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object '$aop_async' to [app] 
2020-01-07 09:11:49,542 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
2020-01-07 09:11:49,542 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dao
2020-01-07 09:11:49,542 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dao
2020-01-07 09:11:49,544 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(dao) in JsonLoader(paths=[ioc/, net.wendal.nutzbook])
2020-01-07 09:11:49,544 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dao'<interface org.nutz.dao.Dao>
2020-01-07 09:11:49,550 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.dao.impl.NutDao without AOP
2020-01-07 09:11:49,550 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dao' to [app] 
2020-01-07 09:11:49,551 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2020-01-07 09:11:49,551 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dataSource
2020-01-07 09:11:49,551 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dataSource
2020-01-07 09:11:49,557 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(dataSource) in JsonLoader(paths=[ioc/, net.wendal.nutzbook])
2020-01-07 09:11:49,557 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dataSource'<>
2020-01-07 09:11:49,632 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class com.alibaba.druid.pool.DruidDataSource without AOP
2020-01-07 09:11:49,632 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dataSource' to [app] 
2020-01-07 09:11:49,697 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2020-01-07 09:11:49,697 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=conf
2020-01-07 09:11:49,697 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=conf
2020-01-07 09:11:49,698 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(conf) in JsonLoader(paths=[ioc/, net.wendal.nutzbook])
2020-01-07 09:11:49,698 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'conf'<>
2020-01-07 09:11:49,699 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.ioc.impl.PropertiesProxy without AOP
2020-01-07 09:11:49,699 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'conf' to [app] 
2020-01-07 09:11:49,704 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( custom/db.properties ) , regex( ^.+[.]properties$ )
2020-01-07 09:11:49,710 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2020-01-07 09:11:49,711 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2020-01-07 09:11:49,712 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2020-01-07 09:11:49,712 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2020-01-07 09:11:49,741 com.alibaba.druid.pool.DruidDataSource.init(DruidDataSource.java:715) INFO  - {dataSource-1} inited
2020-01-07 09:11:49,741 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2020-01-07 09:11:49,761 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) INFO  - Init file-pool by: C:\Users\15522/.nutz/tmp/dao/ [200000]
2020-01-07 09:11:49,762 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) DEBUG - file-pool.home: 'C:\Users\15522\.nutz\tmp\dao'
2020-01-07 09:11:49,762 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) INFO  - file-pool.cursor: 0
2020-01-07 09:11:49,766 org.nutz.dao.jdbc.Jdbcs.<clinit>(Jdbcs.java:90) DEBUG - Jdbcs init complete
2020-01-07 09:11:49,766 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
2020-01-07 09:11:50,048 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:188) DEBUG - select expert : org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert
2020-01-07 09:11:50,053 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:199) DEBUG - JDBC Driver --> mysql-connector-java-5.0.8 ( Revision: ${svn.Revision} )
2020-01-07 09:11:50,053 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:200) DEBUG - JDBC Name   --> MySQL-AB JDBC Driver
2020-01-07 09:11:50,053 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:202) DEBUG - JDBC URL    --> jdbc:mysql://192.168.255.128:3306/test
2020-01-07 09:11:50,059 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_client=utf8
2020-01-07 09:11:50,059 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_connection=utf8
2020-01-07 09:11:50,059 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_database=utf8
2020-01-07 09:11:50,059 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_filesystem=binary
2020-01-07 09:11:50,059 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_results=
2020-01-07 09:11:50,059 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_server=utf8
2020-01-07 09:11:50,060 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_system=utf8
2020-01-07 09:11:50,060 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_sets_dir=/usr/local/mysql/share/charsets/
2020-01-07 09:11:50,061 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:219) DEBUG - Mysql : binlog_format=STATEMENT
2020-01-07 09:11:50,062 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:226) DEBUG - Mysql : database=test
2020-01-07 09:11:50,062 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:232) DEBUG - Mysql : user=root@192.168.255.1
2020-01-07 09:11:50,073 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:221) DEBUG - Database info --> MYSQL:[MySQL - 5.6.45]
2020-01-07 09:11:50,078 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 11 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2020-01-07 09:11:50,133 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT COUNT(*) FROM t_user 
2020-01-07 09:11:50,135 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'scheduler'<interface org.quartz.Scheduler>
2020-01-07 09:11:50,135 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=scheduler
2020-01-07 09:11:50,135 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=scheduler
2020-01-07 09:11:50,135 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(scheduler) in JsonLoader(paths=[ioc/, net.wendal.nutzbook])
2020-01-07 09:11:50,135 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'scheduler'<interface org.quartz.Scheduler>
2020-01-07 09:11:50,136 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'scheduler' to [app] 
2020-01-07 09:11:50,180 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1172) INFO  - Using default implementation for ThreadExecutor
2020-01-07 09:11:50,191 org.quartz.core.SchedulerSignalerImpl.<init>(SchedulerSignalerImpl.java:61) INFO  - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2020-01-07 09:11:50,191 org.quartz.core.QuartzScheduler.<init>(QuartzScheduler.java:240) INFO  - Quartz Scheduler v.2.2.2 created.
2020-01-07 09:11:50,192 org.quartz.simpl.RAMJobStore.initialize(RAMJobStore.java:155) INFO  - RAMJobStore initialized.
2020-01-07 09:11:50,192 org.quartz.core.QuartzScheduler.initialize(QuartzScheduler.java:305) INFO  - Scheduler meta-data: Quartz Scheduler (v2.2.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.

2020-01-07 09:11:50,193 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1327) INFO  - Quartz scheduler 'NutzbookScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
2020-01-07 09:11:50,193 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1331) INFO  - Quartz scheduler version: 2.2.2
2020-01-07 09:11:50,193 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'nutQuartzJobFactory'<>
2020-01-07 09:11:50,193 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=nutQuartzJobFactory
2020-01-07 09:11:50,193 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(nutQuartzJobFactory) in AnnotationIocLoader(packages=[net.wendal.nutzbook.bean, net.wendal.nutzbook.module, net.wendal.nutzbook.quartz, net.wendal.nutzbook.mvc])
2020-01-07 09:11:50,193 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'nutQuartzJobFactory'<>
2020-01-07 09:11:50,193 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class net.wendal.nutzbook.quartz.NutQuartzJobFactory without AOP
2020-01-07 09:11:50,193 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'nutQuartzJobFactory' to [app] 
2020-01-07 09:11:50,194 org.quartz.core.QuartzScheduler.setJobFactory(QuartzScheduler.java:2311) INFO  - JobFactory set to: net.wendal.nutzbook.quartz.NutQuartzJobFactory@c3defe3
2020-01-07 09:11:50,194 org.quartz.core.QuartzScheduler.start(QuartzScheduler.java:575) INFO  - Scheduler NutzbookScheduler_$_NON_CLUSTERED started.
2020-01-07 09:11:50,194 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:276) DEBUG - batch acquisition of 0 triggers
2020-01-07 09:11:50,195 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:141) INFO  - Nutz.Mvc[nutz] is up in 868ms
一月 07, 2020 9:11:50 上午 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare
一月 07, 2020 9:11:51 上午 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.
一月 07, 2020 9:11:51 上午 org.apache.catalina.core.ApplicationContext log
信息: Initializing Shiro environment
2020-01-07 09:11:51,337 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:133) INFO  - Starting Shiro environment initialization.
2020-01-07 09:11:51,368 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: false
2020-01-07 09:11:51,368 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Boolean' value 'false' to type 'Boolean'
2020-01-07 09:11:51,368 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Boolean
2020-01-07 09:11:51,370 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,371 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Byte'
2020-01-07 09:11:51,371 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Byte value '0'
2020-01-07 09:11:51,372 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value:  
2020-01-07 09:11:51,373 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Character' value ' ' to type 'Character'
2020-01-07 09:11:51,373 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Character
2020-01-07 09:11:51,373 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,373 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Double'
2020-01-07 09:11:51,373 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Double value '0.0'
2020-01-07 09:11:51,374 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,374 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Float'
2020-01-07 09:11:51,374 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Float value '0.0'
2020-01-07 09:11:51,374 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,375 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Integer'
2020-01-07 09:11:51,375 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Integer
2020-01-07 09:11:51,375 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,375 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Long'
2020-01-07 09:11:51,375 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Long value '0'
2020-01-07 09:11:51,376 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,376 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Short'
2020-01-07 09:11:51,376 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Short value '0'
2020-01-07 09:11:51,376 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0.0
2020-01-07 09:11:51,376 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'BigDecimal' value '0.0' to type 'BigDecimal'
2020-01-07 09:11:51,376 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a BigDecimal
2020-01-07 09:11:51,377 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,377 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'BigInteger' value '0' to type 'BigInteger'
2020-01-07 09:11:51,377 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a BigInteger
2020-01-07 09:11:51,377 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: false
2020-01-07 09:11:51,377 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Boolean' value 'false' to type 'Boolean'
2020-01-07 09:11:51,377 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Boolean
2020-01-07 09:11:51,377 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,377 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Byte'
2020-01-07 09:11:51,378 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Byte value '0'
2020-01-07 09:11:51,378 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value:  
2020-01-07 09:11:51,378 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Character' value ' ' to type 'Character'
2020-01-07 09:11:51,378 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Character
2020-01-07 09:11:51,378 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,378 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Double'
2020-01-07 09:11:51,378 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Double value '0.0'
2020-01-07 09:11:51,378 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,378 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Float'
2020-01-07 09:11:51,378 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Float value '0.0'
2020-01-07 09:11:51,378 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,378 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Integer'
2020-01-07 09:11:51,379 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Integer
2020-01-07 09:11:51,379 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,379 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Long'
2020-01-07 09:11:51,379 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Long value '0'
2020-01-07 09:11:51,379 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2020-01-07 09:11:51,379 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Short'
2020-01-07 09:11:51,379 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Short value '0'
2020-01-07 09:11:51,380 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 
2020-01-07 09:11:51,380 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'String' value '' to type 'String'
2020-01-07 09:11:51,386 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Z@69db8c66
2020-01-07 09:11:51,386 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'boolean[]' value '[Z@69db8c66' to type 'boolean[]'
2020-01-07 09:11:51,386 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a boolean[]
2020-01-07 09:11:51,386 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [B@7b8c2c5f
2020-01-07 09:11:51,386 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'byte[]' value '[B@7b8c2c5f' to type 'byte[]'
2020-01-07 09:11:51,386 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a byte[]
2020-01-07 09:11:51,386 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [C@3d58bfc8
2020-01-07 09:11:51,386 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'char[]' value '[C@3d58bfc8' to type 'char[]'
2020-01-07 09:11:51,387 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a char[]
2020-01-07 09:11:51,387 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [D@3d3a73c3
2020-01-07 09:11:51,387 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'double[]' value '[D@3d3a73c3' to type 'double[]'
2020-01-07 09:11:51,387 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a double[]
2020-01-07 09:11:51,387 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [F@8b760be
2020-01-07 09:11:51,387 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'float[]' value '[F@8b760be' to type 'float[]'
2020-01-07 09:11:51,388 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a float[]
2020-01-07 09:11:51,388 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [I@12434b23
2020-01-07 09:11:51,389 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'int[]' value '[I@12434b23' to type 'int[]'
2020-01-07 09:11:51,389 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a int[]
2020-01-07 09:11:51,389 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [J@6a9d005c
2020-01-07 09:11:51,389 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'long[]' value '[J@6a9d005c' to type 'long[]'
2020-01-07 09:11:51,389 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a long[]
2020-01-07 09:11:51,389 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [S@457d2a7
2020-01-07 09:11:51,390 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'short[]' value '[S@457d2a7' to type 'short[]'
2020-01-07 09:11:51,390 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a short[]
2020-01-07 09:11:51,390 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.math.BigDecimal;@37d6e003
2020-01-07 09:11:51,390 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'BigDecimal[]' value '[Ljava.math.BigDecimal;@37d6e003' to type 'BigDecimal[]'
2020-01-07 09:11:51,390 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a BigDecimal[]
2020-01-07 09:11:51,390 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.math.BigInteger;@3f44bf89
2020-01-07 09:11:51,390 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'BigInteger[]' value '[Ljava.math.BigInteger;@3f44bf89' to type 'BigInteger[]'
2020-01-07 09:11:51,390 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a BigInteger[]
2020-01-07 09:11:51,390 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Boolean;@3dd9c7dd
2020-01-07 09:11:51,391 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Boolean[]' value '[Ljava.lang.Boolean;@3dd9c7dd' to type 'Boolean[]'
2020-01-07 09:11:51,391 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Boolean[]
2020-01-07 09:11:51,391 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Byte;@721e82b
2020-01-07 09:11:51,391 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Byte[]' value '[Ljava.lang.Byte;@721e82b' to type 'Byte[]'
2020-01-07 09:11:51,391 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Byte[]
2020-01-07 09:11:51,391 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Character;@7f7c634
2020-01-07 09:11:51,391 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Character[]' value '[Ljava.lang.Character;@7f7c634' to type 'Character[]'
2020-01-07 09:11:51,391 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Character[]
2020-01-07 09:11:51,391 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Double;@1d5589b4
2020-01-07 09:11:51,391 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Double[]' value '[Ljava.lang.Double;@1d5589b4' to type 'Double[]'
2020-01-07 09:11:51,391 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Double[]
2020-01-07 09:11:51,391 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Float;@6e4142ec
2020-01-07 09:11:51,392 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Float[]' value '[Ljava.lang.Float;@6e4142ec' to type 'Float[]'
2020-01-07 09:11:51,392 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Float[]
2020-01-07 09:11:51,392 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Integer;@66298fbf
2020-01-07 09:11:51,392 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer[]' value '[Ljava.lang.Integer;@66298fbf' to type 'Integer[]'
2020-01-07 09:11:51,392 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Integer[]
2020-01-07 09:11:51,392 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Long;@5f563328
2020-01-07 09:11:51,392 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Long[]' value '[Ljava.lang.Long;@5f563328' to type 'Long[]'
2020-01-07 09:11:51,392 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Long[]
2020-01-07 09:11:51,392 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Short;@6eff095f
2020-01-07 09:11:51,392 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Short[]' value '[Ljava.lang.Short;@6eff095f' to type 'Short[]'
2020-01-07 09:11:51,392 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Short[]
2020-01-07 09:11:51,392 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.String;@a4d604e
2020-01-07 09:11:51,393 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'String[]' value '[Ljava.lang.String;@a4d604e' to type 'String[]'
2020-01-07 09:11:51,393 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a String[]
2020-01-07 09:11:51,393 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Class;@20b342cf
2020-01-07 09:11:51,393 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Class[]' value '[Ljava.lang.Class;@20b342cf' to type 'Class[]'
2020-01-07 09:11:51,393 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Class[]
2020-01-07 09:11:51,393 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.util.Date;@4e92ea82
2020-01-07 09:11:51,393 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Date[]' value '[Ljava.util.Date;@4e92ea82' to type 'Date[]'
2020-01-07 09:11:51,393 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Date[]
2020-01-07 09:11:51,393 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.util.Calendar;@5831a7fe
2020-01-07 09:11:51,393 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Calendar[]' value '[Ljava.util.Calendar;@5831a7fe' to type 'Calendar[]'
2020-01-07 09:11:51,393 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Calendar[]
2020-01-07 09:11:51,393 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.io.File;@391ff3a6
2020-01-07 09:11:51,394 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.io.File[]' value '[Ljava.io.File;@391ff3a6' to type 'java.io.File[]'
2020-01-07 09:11:51,394 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.io.File[]
2020-01-07 09:11:51,394 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.sql.Date;@3806b70
2020-01-07 09:11:51,394 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.sql.Date[]' value '[Ljava.sql.Date;@3806b70' to type 'java.sql.Date[]'
2020-01-07 09:11:51,394 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.sql.Date[]
2020-01-07 09:11:51,394 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.sql.Time;@5a9f5778
2020-01-07 09:11:51,394 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.sql.Time[]' value '[Ljava.sql.Time;@5a9f5778' to type 'java.sql.Time[]'
2020-01-07 09:11:51,394 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.sql.Time[]
2020-01-07 09:11:51,395 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.sql.Timestamp;@f441582
2020-01-07 09:11:51,395 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.sql.Timestamp[]' value '[Ljava.sql.Timestamp;@f441582' to type 'java.sql.Timestamp[]'
2020-01-07 09:11:51,395 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.sql.Timestamp[]
2020-01-07 09:11:51,395 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.net.URL;@4900550f
2020-01-07 09:11:51,395 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.net.URL[]' value '[Ljava.net.URL;@4900550f' to type 'java.net.URL[]'
2020-01-07 09:11:51,395 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.net.URL[]
2020-01-07 09:11:51,408 org.apache.shiro.web.env.IniWebEnvironment.parseConfig(IniWebEnvironment.java:95) DEBUG - Checking any specified config locations.
2020-01-07 09:11:51,409 org.apache.shiro.web.env.IniWebEnvironment.parseConfig(IniWebEnvironment.java:100) DEBUG - No INI instance or config locations specified.  Trying default config locations.
2020-01-07 09:11:51,412 org.apache.shiro.io.ResourceUtils.loadFromClassPath(ResourceUtils.java:159) DEBUG - Opening resource from class path [shiro.ini]
2020-01-07 09:11:51,413 org.apache.shiro.config.Ini.load(Ini.java:401) DEBUG - Parsing [main]
2020-01-07 09:11:51,413 org.apache.shiro.config.Ini.load(Ini.java:401) DEBUG - Parsing [urls]
2020-01-07 09:11:51,415 org.apache.shiro.web.env.IniWebEnvironment.getDefaultIni(IniWebEnvironment.java:217) DEBUG - Discovered non-empty INI configuration at location 'classpath:shiro.ini'.  Using for configuration.
2020-01-07 09:11:51,416 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:149) DEBUG - Creating instance from Ini [sections=urls]
2020-01-07 09:11:51,457 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:149) DEBUG - Creating instance from Ini [sections=urls]
2020-01-07 09:11:51,460 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/*] from String definition [anon]
2020-01-07 09:11:51,460 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/*] to filter [anon] with config [null]
2020-01-07 09:11:51,461 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:142) DEBUG - Published WebEnvironment as ServletContext attribute with name [org.apache.shiro.web.env.EnvironmentLoader.ENVIRONMENT_ATTRIBUTE_KEY]
2020-01-07 09:11:51,461 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:147) INFO  - Shiro environment initialized in 121 ms.
2020-01-07 09:11:51,485 org.nutz.mvc.NutFilter._init(NutFilter.java:81) INFO  - NutFilter[nutz] starting ...
2020-01-07 09:11:51,530 org.nutz.resource.Scans.<init>(Scans.java:509) DEBUG - Locations for Scans:
[Dir[path=G:\eclipse], Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\nutz-1.r.60.jar], Dir[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\classes], Jar[path=G:\Java\apache-tomcat-7.0.94\bin\bootstrap.jar], Jar[path=G:\Java\apache-tomcat-7.0.94\bin\tomcat-juli.jar]]
2020-01-07 09:11:51,630 org.nutz.resource.Scans.init(Scans.java:105) DEBUG - Locations for Scans:
{G:\eclipse=Dir[path=G:\eclipse], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\nutz-1.r.60.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\nutz-1.r.60.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\classes=Dir[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\classes], G:\Java\apache-tomcat-7.0.94\bin\bootstrap.jar=Jar[path=G:\Java\apache-tomcat-7.0.94\bin\bootstrap.jar], G:\Java\apache-tomcat-7.0.94\bin\tomcat-juli.jar=Jar[path=G:\Java\apache-tomcat-7.0.94\bin\tomcat-juli.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\asm.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\asm.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\rome-1.0.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\rome-1.0.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\saaj.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\saaj.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\wsdl4j-1.5.1.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\wsdl4j-1.5.1.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\activation.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\activation.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\axis-ant.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\axis-ant.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jdom-2.0.4-junit.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jdom-2.0.4-junit.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\shiro-all-1.4.2.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\shiro-all-1.4.2.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jaxrpc.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jaxrpc.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\pinyin4j-2.5.0-sources.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\pinyin4j-2.5.0-sources.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\oscarJDBC.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\oscarJDBC.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jdom-2.0.4-sources.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jdom-2.0.4-sources.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\commons-beanutils-1.9.4.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\commons-beanutils-1.9.4.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jdom-2.0.4-javadoc.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jdom-2.0.4-javadoc.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\log4j-1.2.8.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\log4j-1.2.8.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\druid-1.0.26.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\druid-1.0.26.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\c3p0-0.9.1.1.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\c3p0-0.9.1.1.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\commons-dbcp-1.3.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\commons-dbcp-1.3.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\mysql-connector-java-5.0.8.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\mysql-connector-java-5.0.8.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\XmlSchema-1.0.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\XmlSchema-1.0.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\xerces-2.6.2.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\xerces-2.6.2.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\commons-io-1.4.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\commons-io-1.4.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\axis.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\axis.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jdom-2.0.4.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jdom-2.0.4.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\commons-discovery-0.2.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\commons-discovery-0.2.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\xml-apis.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\xml-apis.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\commons-codec-1.11.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\commons-codec-1.11.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jstl-1.2.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jstl-1.2.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\mail.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\mail.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\quartz-jobs-2.2.2.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\quartz-jobs-2.2.2.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\dom4j-1.6.1.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\dom4j-1.6.1.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jdom-2.0.4-contrib.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\jdom-2.0.4-contrib.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\pinyin4j-2.5.0.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\pinyin4j-2.5.0.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\slf4j-api-1.7.7.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\slf4j-api-1.7.7.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\asm-attrs.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\asm-attrs.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\commons-logging-1.1.1.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\commons-logging-1.1.1.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\quartz-2.2.2.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\quartz-2.2.2.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\log4j-1.2.16.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\log4j-1.2.16.jar], E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\slf4j-log4j12-1.7.7.jar=Jar[path=E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\lib\slf4j-log4j12-1.7.7.jar]}
2020-01-07 09:11:51,663 org.nutz.castor.Castors.reload(Castors.java:115) DEBUG - Using 95 castor for Castors
2020-01-07 09:11:51,680 org.nutz.mvc.config.AbstractNutConfig.createLoading(AbstractNutConfig.java:59) DEBUG - Loading by class org.nutz.mvc.impl.NutLoading
2020-01-07 09:11:51,681 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:55) INFO  - Nutz Version : 1.r.60 
2020-01-07 09:11:51,681 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:56) INFO  - Nutz.Mvc[nutz] is initializing ...
2020-01-07 09:11:51,681 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:60) DEBUG - Web Container Information:
2020-01-07 09:11:51,682 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:61) DEBUG -  - Default Charset : UTF-8
2020-01-07 09:11:51,682 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:62) DEBUG -  - Current . path  : G:\eclipse\.
2020-01-07 09:11:51,682 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:63) DEBUG -  - Java Version    : 1.8.0_77
2020-01-07 09:11:51,682 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:64) DEBUG -  - File separator  : \
2020-01-07 09:11:51,682 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:65) DEBUG -  - Timezone        : Asia/Shanghai
2020-01-07 09:11:51,682 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:66) DEBUG -  - OS              : Windows 10 amd64
2020-01-07 09:11:51,682 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:67) DEBUG -  - ServerInfo      : Apache Tomcat/7.0.94
2020-01-07 09:11:51,682 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:68) DEBUG -  - Servlet API     : 3.0
2020-01-07 09:11:51,682 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:73) DEBUG -  - ContextPath     : /nutzbook_dev_prepare
2020-01-07 09:11:51,682 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:74) DEBUG -  - context.tempdir : E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\work\Catalina\localhost\nutzbook_dev_prepare
2020-01-07 09:11:51,682 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:75) DEBUG -  - MainModule      : net.wendal.nutzbook.MainModule
2020-01-07 09:11:51,683 org.nutz.mvc.impl.NutLoading.createContext(NutLoading.java:238) DEBUG - >> app.root = E:/eclipse/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/webapps/nutzbook_dev_prepare
2020-01-07 09:11:51,684 org.nutz.mvc.impl.NutLoading.createIoc(NutLoading.java:379) DEBUG - @IocBy(type=org.nutz.mvc.ioc.provider.ComboIocProvider, args=["*js", "ioc/", "net.wendal.nutzbook", "*anno", "net.wendal.nutzbook.bean", "net.wendal.nutzbook.module", "net.wendal.nutzbook.quartz", "net.wendal.nutzbook.mvc", "*tx", "*async"],init=[])
2020-01-07 09:11:51,696 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 2 resource by src( ioc/ ) , regex( ^(.+[.])(js|json)$ )
2020-01-07 09:11:51,698 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 0 resource by src( net.wendal.nutzbook ) , regex( ^(.+[.])(js|json)$ )
2020-01-07 09:11:51,699 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:48) DEBUG - loading ioc js config from [dao.js]
2020-01-07 09:11:51,701 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:48) DEBUG - loading ioc js config from [quartz.js]
2020-01-07 09:11:51,701 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:56) DEBUG - Loaded 4 bean define from path=[ioc/, net.wendal.nutzbook] --> [scheduler, conf, dao, dataSource]
2020-01-07 09:11:51,705 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 3 resource by src( net/wendal/nutzbook/bean/ ) , regex( ^.+[.]class$ )
2020-01-07 09:11:51,713 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 3 resource by src( net/wendal/nutzbook/module/ ) , regex( ^.+[.]class$ )
2020-01-07 09:11:51,718 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserModule
2020-01-07 09:11:51,722 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserProfileModule
2020-01-07 09:11:51,729 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( net/wendal/nutzbook/quartz/ ) , regex( ^.+[.]class$ )
2020-01-07 09:11:51,730 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.quartz.NutQuartzJobFactory
2020-01-07 09:11:51,733 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 2 resource by src( net/wendal/nutzbook/mvc/ ) , regex( ^.+[.]class$ )
2020-01-07 09:11:51,735 org.nutz.ioc.loader.annotation.AnnotationIocLoader.<init>(AnnotationIocLoader.java:50) INFO  - Found 3 classes in 4 base-packages!
beans = ["nutQuartzJobFactory", "userProfileModule", "userModule"]
2020-01-07 09:11:51,736 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:40) DEBUG - Loaded 5 bean define from reader --
[txREPEATABLE_READ, txSERIALIZABLE, txNONE, txREAD_UNCOMMITTED, txREAD_COMMITTED]
2020-01-07 09:11:51,737 org.nutz.ioc.aop.SimpleAopMaker.<init>(SimpleAopMaker.java:79) DEBUG - Load AopConfigure for anno=org.nutz.aop.interceptor.async.Async by type=org.nutz.aop.interceptor.async.AsyncAopIocLoader
2020-01-07 09:11:51,739 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:98) INFO  - NutIoc init begin ...
2020-01-07 09:11:51,740 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:115) INFO  - ... NutIoc init complete
2020-01-07 09:11:51,742 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:159) INFO  - Build URL mapping by org.nutz.mvc.impl.UrlMappingImpl ...
2020-01-07 09:11:51,743 org.nutz.mvc.impl.NutLoading.createViewMakers(NutLoading.java:369) DEBUG - @Views(DefaultViewMaker.class)
2020-01-07 09:11:51,748 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( mvc/nutzbook-mvc-chain.js ) , regex( ^(.+[.])(js|json)$ )
2020-01-07 09:11:51,750 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.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"
   }
}
2020-01-07 09:11:51,750 org.nutz.mvc.impl.NutLoading.createChainMaker(NutLoading.java:266) DEBUG - @ChainBy(org.nutz.mvc.impl.NutActionChainMaker)
2020-01-07 09:11:51,753 org.nutz.mvc.impl.Loadings.scanModules(Loadings.java:154) DEBUG - module class location 'file:/E:/eclipse/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/webapps/nutzbook_dev_prepare/WEB-INF/classes/'
2020-01-07 09:11:51,753 org.nutz.mvc.impl.Loadings.scanModuleInPackage(Loadings.java:184) DEBUG -  > scan 'net.wendal.nutzbook'
2020-01-07 09:11:51,757 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 11 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2020-01-07 09:11:51,757 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG -    >> add 'net.wendal.nutzbook.module.UserModule'
2020-01-07 09:11:51,757 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG -    >> add 'net.wendal.nutzbook.module.UserProfileModule'
2020-01-07 09:11:51,767 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/'                    >> (UserModule.java:100).index           : void       | @Ok(jsp:jsp.user.list) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,774 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/add'                 >> (UserModule.java:55).add              : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,775 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/count'               >> (UserModule.java:32).count            : int        | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,776 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/update'              >> (UserModule.java:66).update           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,780 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/delete'              >> (UserModule.java:80).delete           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,782 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/query'               >> (UserModule.java:89).query            : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,782 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/error'               >> (UserModule.java:137).error           : void       | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,783 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/login'               >> (UserModule.java:38).login            : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,784 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/logout'              >> (UserModule.java:50).logout           : void       | @Ok(>>:/ ) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,786 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/'            >> (UserProfileModule.java:148).index    : UserProfile | @Ok(jsp:jsp.user.profile) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,787 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/get'         >> (UserProfileModule.java:50).get       : UserProfile | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,788 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/update'      >> (UserProfileModule.java:65).update    : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,789 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/delete'      >> (UserProfileModule.java:92).delete    : Object     | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,791 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:138).readAvatar : Object     | @Ok(raw:jpg) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,794 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) INFO  - Init file-pool by: E:/eclipse/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/webapps/nutzbook_dev_prepare/WEB-INF/tmp/user_avatar [20000]
2020-01-07 09:11:51,796 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) DEBUG - file-pool.home: 'E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare\WEB-INF\tmp\user_avatar'
2020-01-07 09:11:51,797 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) INFO  - file-pool.cursor: 0
2020-01-07 09:11:51,799 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:108).uploadAvatar : void       | @Ok(>>:/user/profile) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2020-01-07 09:11:51,800 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:220) INFO  - Found 15 module methods
2020-01-07 09:11:51,800 org.nutz.mvc.impl.NutLoading.evalLocalization(NutLoading.java:306) DEBUG - Localization: org.nutz.mvc.impl.NutMessageLoader('msg/')  dft<zh-CN>
2020-01-07 09:11:51,802 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( msg/ ) , regex( ^.+[.]properties$ )
2020-01-07 09:11:51,802 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:27) DEBUG - Load Messages in 1 resource : [[NutResource[zh-CN/user.properties]]]
2020-01-07 09:11:51,805 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:95) DEBUG - Message Loaded, size = 2
2020-01-07 09:11:51,806 org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:274) INFO  - Setup application...
2020-01-07 09:11:51,806 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dao'<interface org.nutz.dao.Dao>
2020-01-07 09:11:51,807 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get '$aop_async'<interface org.nutz.ioc.aop.config.AopConfigration>
2020-01-07 09:11:51,807 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=$aop_async
2020-01-07 09:11:51,810 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject($aop_async) in AsyncAopIocLoader@1140232793
2020-01-07 09:11:51,810 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'$aop_async'<interface org.nutz.ioc.aop.config.AopConfigration>
2020-01-07 09:11:51,811 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object '$aop_async' to [app] 
2020-01-07 09:11:51,815 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
2020-01-07 09:11:51,815 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dao
2020-01-07 09:11:51,816 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dao
2020-01-07 09:11:51,819 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(dao) in JsonLoader(paths=[ioc/, net.wendal.nutzbook])
2020-01-07 09:11:51,819 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dao'<interface org.nutz.dao.Dao>
2020-01-07 09:11:51,827 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.dao.impl.NutDao without AOP
2020-01-07 09:11:51,827 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dao' to [app] 
2020-01-07 09:11:51,828 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2020-01-07 09:11:51,828 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dataSource
2020-01-07 09:11:51,828 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dataSource
2020-01-07 09:11:51,833 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(dataSource) in JsonLoader(paths=[ioc/, net.wendal.nutzbook])
2020-01-07 09:11:51,833 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dataSource'<>
2020-01-07 09:11:51,849 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class com.alibaba.druid.pool.DruidDataSource without AOP
2020-01-07 09:11:51,849 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dataSource' to [app] 
2020-01-07 09:11:51,859 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2020-01-07 09:11:51,859 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=conf
2020-01-07 09:11:51,859 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=conf
2020-01-07 09:11:51,859 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(conf) in JsonLoader(paths=[ioc/, net.wendal.nutzbook])
2020-01-07 09:11:51,859 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'conf'<>
2020-01-07 09:11:51,859 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.ioc.impl.PropertiesProxy without AOP
2020-01-07 09:11:51,860 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'conf' to [app] 
2020-01-07 09:11:51,861 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( custom/db.properties ) , regex( ^.+[.]properties$ )
2020-01-07 09:11:51,864 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2020-01-07 09:11:51,864 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2020-01-07 09:11:51,865 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2020-01-07 09:11:51,865 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2020-01-07 09:11:51,894 com.alibaba.druid.pool.DruidDataSource.init(DruidDataSource.java:715) INFO  - {dataSource-1} inited
2020-01-07 09:11:51,895 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2020-01-07 09:11:51,912 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) INFO  - Init file-pool by: C:\Users\15522/.nutz/tmp/dao/ [200000]
2020-01-07 09:11:51,913 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) DEBUG - file-pool.home: 'C:\Users\15522\.nutz\tmp\dao'
2020-01-07 09:11:51,913 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) INFO  - file-pool.cursor: 0
2020-01-07 09:11:51,918 org.nutz.dao.jdbc.Jdbcs.<clinit>(Jdbcs.java:90) DEBUG - Jdbcs init complete
2020-01-07 09:11:51,918 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
2020-01-07 09:11:52,168 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:188) DEBUG - select expert : org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert
2020-01-07 09:11:52,173 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:199) DEBUG - JDBC Driver --> mysql-connector-java-5.0.8 ( Revision: ${svn.Revision} )
2020-01-07 09:11:52,173 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:200) DEBUG - JDBC Name   --> MySQL-AB JDBC Driver
2020-01-07 09:11:52,173 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:202) DEBUG - JDBC URL    --> jdbc:mysql://192.168.255.128:3306/test
2020-01-07 09:11:52,180 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_client=utf8
2020-01-07 09:11:52,180 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_connection=utf8
2020-01-07 09:11:52,180 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_database=utf8
2020-01-07 09:11:52,180 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_filesystem=binary
2020-01-07 09:11:52,180 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_results=
2020-01-07 09:11:52,180 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_server=utf8
2020-01-07 09:11:52,181 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_system=utf8
2020-01-07 09:11:52,181 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_sets_dir=/usr/local/mysql/share/charsets/
2020-01-07 09:11:52,182 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:219) DEBUG - Mysql : binlog_format=STATEMENT
2020-01-07 09:11:52,182 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:226) DEBUG - Mysql : database=test
2020-01-07 09:11:52,183 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:232) DEBUG - Mysql : user=root@192.168.255.1
2020-01-07 09:11:52,195 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:221) DEBUG - Database info --> MYSQL:[MySQL - 5.6.45]
2020-01-07 09:11:52,200 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 11 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2020-01-07 09:11:52,245 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT COUNT(*) FROM t_user 
2020-01-07 09:11:52,247 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'scheduler'<interface org.quartz.Scheduler>
2020-01-07 09:11:52,247 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=scheduler
2020-01-07 09:11:52,247 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=scheduler
2020-01-07 09:11:52,247 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(scheduler) in JsonLoader(paths=[ioc/, net.wendal.nutzbook])
2020-01-07 09:11:52,247 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'scheduler'<interface org.quartz.Scheduler>
2020-01-07 09:11:52,247 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'scheduler' to [app] 
2020-01-07 09:11:52,272 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1172) INFO  - Using default implementation for ThreadExecutor
2020-01-07 09:11:52,361 org.quartz.core.SchedulerSignalerImpl.<init>(SchedulerSignalerImpl.java:61) INFO  - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2020-01-07 09:11:52,361 org.quartz.core.QuartzScheduler.<init>(QuartzScheduler.java:240) INFO  - Quartz Scheduler v.2.2.2 created.
2020-01-07 09:11:52,362 org.quartz.simpl.RAMJobStore.initialize(RAMJobStore.java:155) INFO  - RAMJobStore initialized.
2020-01-07 09:11:52,362 org.quartz.core.QuartzScheduler.initialize(QuartzScheduler.java:305) INFO  - Scheduler meta-data: Quartz Scheduler (v2.2.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.

2020-01-07 09:11:52,363 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1327) INFO  - Quartz scheduler 'NutzbookScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
2020-01-07 09:11:52,363 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1331) INFO  - Quartz scheduler version: 2.2.2
2020-01-07 09:11:52,363 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'nutQuartzJobFactory'<>
2020-01-07 09:11:52,363 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=nutQuartzJobFactory
2020-01-07 09:11:52,363 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(nutQuartzJobFactory) in AnnotationIocLoader(packages=[net.wendal.nutzbook.bean, net.wendal.nutzbook.module, net.wendal.nutzbook.quartz, net.wendal.nutzbook.mvc])
2020-01-07 09:11:52,363 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'nutQuartzJobFactory'<>
2020-01-07 09:11:52,363 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class net.wendal.nutzbook.quartz.NutQuartzJobFactory without AOP
2020-01-07 09:11:52,363 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'nutQuartzJobFactory' to [app] 
2020-01-07 09:11:52,364 org.quartz.core.QuartzScheduler.setJobFactory(QuartzScheduler.java:2311) INFO  - JobFactory set to: net.wendal.nutzbook.quartz.NutQuartzJobFactory@49879658
2020-01-07 09:11:52,364 org.quartz.core.QuartzScheduler.start(QuartzScheduler.java:575) INFO  - Scheduler NutzbookScheduler_$_NON_CLUSTERED started.
2020-01-07 09:11:52,364 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:276) DEBUG - batch acquisition of 0 triggers
2020-01-07 09:11:52,364 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:141) INFO  - Nutz.Mvc[nutz] is up in 681ms
一月 07, 2020 9:11:52 上午 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deployment of web application directory E:\eclipse\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\webapps\nutzbook_dev_prepare has finished in 2,147 ms
一月 07, 2020 9:11:52 上午 org.apache.coyote.AbstractProtocol start
信息: Starting ProtocolHandler ["http-bio-8080"]
一月 07, 2020 9:11:52 上午 org.apache.coyote.AbstractProtocol start
信息: Starting ProtocolHandler ["ajp-bio-8009"]
一月 07, 2020 9:11:52 上午 org.apache.catalina.startup.Catalina start
信息: Server startup in 6887 ms
2020-01-07 09:12:14,583 org.nutz.mvc.impl.UrlMappingImpl.get(UrlMappingImpl.java:101) DEBUG - Found mapping for [GET] path=/user/profile : UserProfileModule.index(UserProfileModule.java:148)
2020-01-07 09:12:14,584 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'userProfileModule'<class net.wendal.nutzbook.module.UserProfileModule>
2020-01-07 09:12:14,585 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=userProfileModule
2020-01-07 09:12:14,585 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(userProfileModule) in AnnotationIocLoader(packages=[net.wendal.nutzbook.bean, net.wendal.nutzbook.module, net.wendal.nutzbook.quartz, net.wendal.nutzbook.mvc])
2020-01-07 09:12:14,585 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'userProfileModule'<class net.wendal.nutzbook.module.UserProfileModule>
2020-01-07 09:12:14,586 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'txREAD_COMMITTED'<interface org.nutz.aop.MethodInterceptor>
2020-01-07 09:12:14,586 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=txREAD_COMMITTED
2020-01-07 09:12:14,586 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=txREAD_COMMITTED
2020-01-07 09:12:14,587 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(txREAD_COMMITTED) in TransIocLoader@171193549
2020-01-07 09:12:14,587 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'txREAD_COMMITTED'<interface org.nutz.aop.MethodInterceptor>
2020-01-07 09:12:14,587 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'txREAD_COMMITTED' to [app] 
2020-01-07 09:12:14,590 org.nutz.aop.asm.AsmClassAgent.<clinit>(AsmClassAgent.java:29) DEBUG - AsmClassAgent will define class in Version 50
2020-01-07 09:12:14,597 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'userProfileModule' to [app] 
2020-01-07 09:12:14,598 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dao'<interface org.nutz.dao.Dao>
2020-01-07 09:12:14,600 net.wendal.nutzbook.mvc.LogTimeProcessor.process(LogTimeProcessor.java:27) DEBUG - [ GET]URI=/nutzbook_dev_prepare/user/profile 17ms
2020-01-07 09:12:17,764 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:276) DEBUG - batch acquisition of 0 triggers
2020-01-07 09:12:20,383 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:276) DEBUG - batch acquisition of 0 triggers
2020-01-07 09:12:45,724 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:276) DEBUG - batch acquisition of 0 triggers
2020-01-07 09:12:46,613 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:276) DEBUG - batch acquisition of 0 triggers
2020-01-07 09:13:11,010 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:276) DEBUG - batch acquisition of 0 triggers
2020-01-07 09:13:11,715 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:276) DEBUG - batch acquisition of 0 triggers

我访问这个地址，http://127.0.0.1:8080/nutzbook/user/profile/。就会直接进入的到登录界面，而不是上传头像界面